builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-037 starttime: 1500397020.3 results: success (0) revision: 56c6c81993e6b0f0e094e0c88b42ce3ec566ead9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-18 09:57:00.297811) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-18 09:57:00.298265) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-18 09:57:00.298542) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-18 09:57:00.348279) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 09:57:00.348549) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-18 09:57:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2017-07-18 09:57:00 (221 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.094339 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 09:57:00.458835) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 09:57:00.459160) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014164 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 09:57:00.509411) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-18 09:57:00.509712) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 56c6c81993e6b0f0e094e0c88b42ce3ec566ead9 --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 56c6c81993e6b0f0e094e0c88b42ce3ec566ead9 --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=56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-18 09:57:00,658 truncating revision to first 12 chars 2017-07-18 09:57:00,658 Setting DEBUG logging. 2017-07-18 09:57:00,658 attempt 1/10 2017-07-18 09:57:00,658 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/56c6c81993e6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-18 09:57:01,626 unpacking tar archive at: mozilla-central-56c6c81993e6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.274794 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-18 09:57:01.807954) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-18 09:57:01.808264) ========= script_repo_revision: 56c6c81993e6b0f0e094e0c88b42ce3ec566ead9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-18 09:57:01.808637) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-18 09:57:01.808920) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-18 09:57:01.819124) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-07-18 09:57:01.819436) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:57:02 INFO - MultiFileLogger online at 20170718 09:57:02 in /builds/slave/test 09:57:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:57:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:57:02 INFO - {'append_to_log': False, 09:57:02 INFO - 'base_work_dir': '/builds/slave/test', 09:57:02 INFO - 'blob_upload_branch': 'Firefox', 09:57:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:57:02 INFO - 'branch': 'Firefox', 09:57:02 INFO - 'buildbot_json_path': 'buildprops.json', 09:57:02 INFO - 'code_coverage': False, 09:57:02 INFO - 'config_files': ('talos/linux_config.py',), 09:57:02 INFO - 'default_actions': ('clobber', 09:57:02 INFO - 'read-buildbot-config', 09:57:02 INFO - 'download-and-extract', 09:57:02 INFO - 'populate-webroot', 09:57:02 INFO - 'create-virtualenv', 09:57:02 INFO - 'install', 09:57:02 INFO - 'setup-mitmproxy', 09:57:02 INFO - 'run-tests'), 09:57:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:57:02 INFO - 'disable_ccov_upload': False, 09:57:02 INFO - 'download_minidump_stackwalk': True, 09:57:02 INFO - 'download_symbols': 'ondemand', 09:57:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:57:02 INFO - 'tooltool.py': '/tools/tooltool.py', 09:57:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:57:02 INFO - '/tools/misc-python/virtualenv.py')}, 09:57:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:57:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:57:02 INFO - 'gecko_profile': False, 09:57:02 INFO - 'gecko_profile_interval': 0, 09:57:02 INFO - 'installer_path': 'installer.exe', 09:57:02 INFO - 'log_level': 'info', 09:57:02 INFO - 'log_name': 'talos', 09:57:02 INFO - 'log_to_console': True, 09:57:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:57:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:57:02 INFO - 'opt_config_files': (), 09:57:02 INFO - 'pip_index': False, 09:57:02 INFO - 'suite': 'dromaeojs-e10s', 09:57:02 INFO - 'system_bits': '32', 09:57:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:57:02 INFO - 'title': 'talos-linux64-ix-037', 09:57:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:57:02 INFO - 'use_talos_json': True, 09:57:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:57:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:57:02 INFO - 'work_dir': 'build'} 09:57:02 INFO - [mozharness: 2017-07-18 16:57:02.066432Z] Running clobber step. 09:57:02 INFO - Running pre-action listener: _resource_record_pre_action 09:57:02 INFO - Running main action method: clobber 09:57:02 INFO - rmtree: /builds/slave/test/build 09:57:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:57:03 INFO - Running post-action listener: _resource_record_post_action 09:57:03 INFO - [mozharness: 2017-07-18 16:57:03.605744Z] Finished clobber step (success) 09:57:03 INFO - [mozharness: 2017-07-18 16:57:03.605844Z] Running read-buildbot-config step. 09:57:03 INFO - Running pre-action listener: _resource_record_pre_action 09:57:03 INFO - Running main action method: read_buildbot_config 09:57:03 INFO - Using buildbot properties: 09:57:03 INFO - { 09:57:03 INFO - "project": "", 09:57:03 INFO - "product": "firefox", 09:57:03 INFO - "who": "ryanvm@gmail.com", 09:57:03 INFO - "installer_path": "public/build/target.tar.bz2", 09:57:03 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 09:57:03 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:57:03 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 09:57:03 INFO - "stage_platform": "linux64-stylo", 09:57:03 INFO - "basedir": "/builds/slave/test", 09:57:03 INFO - "buildnumber": 33, 09:57:03 INFO - "platform": "ubuntu64_hw_stylo", 09:57:03 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:57:03 INFO - "slavebuilddir": "test", 09:57:03 INFO - "taskId": "ZQYFpwtlRj6b84wv3F018A", 09:57:03 INFO - "branch": "mozilla-central", 09:57:03 INFO - "script_repo_revision": "production", 09:57:03 INFO - "revision": "56c6c81993e6b0f0e094e0c88b42ce3ec566ead9", 09:57:03 INFO - "slavename": "talos-linux64-ix-037", 09:57:03 INFO - "repo_path": "mozilla-central" 09:57:03 INFO - } 09:57:03 INFO - Finding installer, test and symbols from parent task. 09:57:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZQYFpwtlRj6b84wv3F018A'}, attempt #1 09:57:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZQYFpwtlRj6b84wv3F018A'}, attempt #1 09:57:04 INFO - Task dependencies: Zr7KnPKKQ_OSHQjb4HXsZg 09:57:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg'}, attempt #1 09:57:04 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.tar.bz2 09:57:04 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/test_packages.json 09:57:04 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.crashreporter-symbols.zip 09:57:04 INFO - Running post-action listener: _resource_record_post_action 09:57:04 INFO - [mozharness: 2017-07-18 16:57:04.965233Z] Finished read-buildbot-config step (success) 09:57:04 INFO - [mozharness: 2017-07-18 16:57:04.965494Z] Running download-and-extract step. 09:57:04 INFO - Running pre-action listener: _resource_record_pre_action 09:57:04 INFO - Running main action method: download_and_extract 09:57:04 INFO - mkdir: /builds/slave/test/build/tests 09:57:04 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')]} 09:57:04 INFO - trying https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.test_packages.json 09:57:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:57:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:57:05 INFO - Downloaded 989 bytes. 09:57:05 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:57:05 INFO - Using the following test package requirements: 09:57:05 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:57:05 INFO - u'common': [u'target.common.tests.zip'], 09:57:05 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:57:05 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:57:05 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:57:05 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:57:05 INFO - u'mozbase': [u'target.common.tests.zip'], 09:57:05 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:57:05 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:57:05 INFO - u'web-platform': [u'target.common.tests.zip', 09:57:05 INFO - u'target.web-platform.tests.tar.gz'], 09:57:05 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:57:05 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:57:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.common.tests.zip 09:57:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:57:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.common.tests.zip into memory 09:57:06 INFO - Content-Length response header: 72757926 09:57:06 INFO - Bytes received: 72757926 09:57:17 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:57:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.common.tests.zip 09:57:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:57:17 INFO - Fetch https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.common.tests.zip into memory 09:57:19 INFO - Content-Length response header: 72757926 09:57:19 INFO - Bytes received: 72757926 09:57:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.talos.tests.zip 09:57:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:57:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.talos.tests.zip into memory 09:57:33 INFO - Content-Length response header: 14495033 09:57:33 INFO - Bytes received: 14495033 09:57: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')]} 09:57:34 INFO - trying https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.tar.bz2 09:57:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:57:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:57:35 INFO - Downloaded 61981570 bytes. 09:57:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.tar.bz2 09:57:35 INFO - mkdir: /builds/slave/test/properties 09:57:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:57:35 INFO - Writing to file /builds/slave/test/properties/build_url 09:57:35 INFO - Contents: 09:57:35 INFO - build_url:https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.tar.bz2 09:57:35 INFO - Running post-action listener: _resource_record_post_action 09:57:35 INFO - Running post-action listener: set_extra_try_arguments 09:57:35 INFO - [mozharness: 2017-07-18 16:57:35.224399Z] Finished download-and-extract step (success) 09:57:35 INFO - [mozharness: 2017-07-18 16:57:35.224495Z] Running populate-webroot step. 09:57:35 INFO - Running pre-action listener: _resource_record_pre_action 09:57:35 INFO - Running main action method: populate_webroot 09:57:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:57:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:57:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:57:35 INFO - '--tppagecycles', 09:57:35 INFO - '7'], 09:57:35 INFO - 'tests': ['tsvgm']}}, 09:57:35 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:57:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:57:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:57:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:57:35 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:57:35 INFO - 'talos_options': ['--disable-e10s'], 09:57:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:57:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:57:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:57:35 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:57:35 INFO - 'talos_options': ['--disable-e10s'], 09:57:35 INFO - 'tests': ['damp', 'tps']}, 09:57:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:57:35 INFO - 'tests': ['damp', 'tps']}, 09:57:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:57:35 INFO - 'tests': ['dromaeo_dom']}, 09:57:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:57:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:57:35 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:57:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:57:35 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:57:35 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:57:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:57:35 INFO - 'tests': ['a11yr', 09:57:35 INFO - 'ts_paint', 09:57:35 INFO - 'tpaint', 09:57:35 INFO - 'sessionrestore', 09:57:35 INFO - 'sessionrestore_no_auto_restore', 09:57:35 INFO - 'tabpaint']}, 09:57:35 INFO - 'other-e10s': {'tests': ['a11yr', 09:57:35 INFO - 'ts_paint', 09:57:35 INFO - 'tpaint', 09:57:35 INFO - 'sessionrestore', 09:57:35 INFO - 'sessionrestore_many_windows', 09:57:35 INFO - 'sessionrestore_no_auto_restore', 09:57:35 INFO - 'tabpaint']}, 09:57:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:57:35 INFO - 'ts_paint', 09:57:35 INFO - 'tpaint', 09:57:35 INFO - 'sessionrestore', 09:57:35 INFO - 'sessionrestore_many_windows', 09:57:35 INFO - 'sessionrestore_no_auto_restore', 09:57:35 INFO - 'tabpaint']}, 09:57:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:57:35 INFO - 'ts_paint', 09:57:35 INFO - 'tpaint', 09:57:35 INFO - 'sessionrestore', 09:57:35 INFO - 'sessionrestore_many_windows', 09:57:35 INFO - 'sessionrestore_no_auto_restore', 09:57:35 INFO - 'tabpaint']}, 09:57:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:57:35 INFO - 'tests': ['a11yr', 09:57:35 INFO - 'ts_paint', 09:57:35 INFO - 'tpaint', 09:57:35 INFO - 'sessionrestore', 09:57:35 INFO - 'sessionrestore_no_auto_restore', 09:57:35 INFO - 'tabpaint']}, 09:57:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:57:35 INFO - 'tests': ['a11yr', 09:57:35 INFO - 'ts_paint', 09:57:35 INFO - 'tpaint', 09:57:35 INFO - 'sessionrestore', 09:57:35 INFO - 'sessionrestore_no_auto_restore', 09:57:35 INFO - 'tabpaint']}, 09:57:35 INFO - 'perf-reftest': {'tests': ['bloom_basic']}, 09:57:35 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 09:57:35 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 09:57:35 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:57:35 INFO - 'talos_options': ['--mitmproxy', 09:57:35 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:57:35 INFO - '--firstNonBlankPaint'], 09:57:35 INFO - 'tests': ['quantum_pageload_google', 09:57:35 INFO - 'quantum_pageload_youtube', 09:57:35 INFO - 'quantum_pageload_amazon', 09:57:35 INFO - 'quantum_pageload_facebook']}, 09:57:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:57:35 INFO - 'tests': ['tsvgx', 09:57:35 INFO - 'tsvgr_opacity', 09:57:35 INFO - 'tart', 09:57:35 INFO - 'tscrollx', 09:57:35 INFO - 'cart', 09:57:35 INFO - 'tsvg_static']}, 09:57:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:57:35 INFO - 'tsvgr_opacity', 09:57:35 INFO - 'tart', 09:57:35 INFO - 'tscrollx', 09:57:35 INFO - 'cart', 09:57:35 INFO - 'tsvg_static']}, 09:57:35 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:57:35 INFO - 'talos_options': ['--disable-e10s'], 09:57:35 INFO - 'tests': ['tp5o']}, 09:57:35 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:57:35 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:57:35 INFO - 'talos_options': ['--disable-e10s', 09:57:35 INFO - '--xperf_path', 09:57:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:57:35 INFO - 'tests': ['tp5n']}, 09:57:35 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:57:35 INFO - 'talos_options': ['--xperf_path', 09:57:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:57:35 INFO - 'tests': ['tp5n']}}, 09:57:35 INFO - 'talos.zip': {'path': '', 09:57:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:57:35 INFO - Running post-action listener: _resource_record_post_action 09:57:35 INFO - [mozharness: 2017-07-18 16:57:35.239709Z] Finished populate-webroot step (success) 09:57:35 INFO - [mozharness: 2017-07-18 16:57:35.239800Z] Running create-virtualenv step. 09:57:35 INFO - Running pre-action listener: _resource_record_pre_action 09:57:35 INFO - Running main action method: create_virtualenv 09:57:35 INFO - Creating virtualenv /builds/slave/test/build/venv 09:57:35 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 09:57:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:57:35 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:57:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:57:35 INFO - Using real prefix '/usr' 09:57:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:57:35 INFO - Installing distribute.............................................................................................................................................................................................done. 09:57:37 INFO - Installing pip.................done. 09:57:37 INFO - Return code: 0 09:57:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:57:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:57:38 INFO - Reading from file tmpfile_stdout 09:57:38 INFO - Output received: 09:57:38 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) 09:57:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:57: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')]} 09:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57: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')]} 09:57:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1620d00>, '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 0x1621c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1622090>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', '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 09:57: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', 'pip>=1.5'] in /builds/slave/test/build 09:57: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 pip>=1.5 09:57:38 INFO - Using env: {'DISPLAY': ':0', 09:57:38 INFO - 'HOME': '/home/cltbld', 09:57:38 INFO - 'LANG': 'en_US.UTF-8', 09:57:38 INFO - 'LANGUAGE': 'en_US:en', 09:57:38 INFO - 'LOGNAME': 'cltbld', 09:57:38 INFO - 'MAIL': '/var/mail/cltbld', 09:57:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:38 INFO - 'MOZ_NO_REMOTE': '1', 09:57:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:57:38 INFO - 'NO_EM_RESTART': '1', 09:57:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:57:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:38 INFO - 'PWD': '/builds/slave/test', 09:57:38 INFO - 'SHELL': '/bin/bash', 09:57:38 INFO - 'SHLVL': '1', 09:57:38 INFO - 'TERM': 'linux', 09:57:38 INFO - 'TMOUT': '86400', 09:57:38 INFO - 'USER': 'cltbld', 09:57:38 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', 09:57:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:38 INFO - '_': '/tools/buildbot/bin/python'} 09:57:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:38 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 09:57:38 INFO - Cleaning up... 09:57:38 INFO - Return code: 0 09:57:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:57: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')]} 09:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57: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')]} 09:57:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57: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', '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 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1620d00>, '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 0x1621c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1622090>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', '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 09:57: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:57: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 psutil>=3.1.1 09:57:38 INFO - Using env: {'DISPLAY': ':0', 09:57:38 INFO - 'HOME': '/home/cltbld', 09:57:38 INFO - 'LANG': 'en_US.UTF-8', 09:57:38 INFO - 'LANGUAGE': 'en_US:en', 09:57:38 INFO - 'LOGNAME': 'cltbld', 09:57:38 INFO - 'MAIL': '/var/mail/cltbld', 09:57:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:38 INFO - 'MOZ_NO_REMOTE': '1', 09:57:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:57:38 INFO - 'NO_EM_RESTART': '1', 09:57:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:57:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:38 INFO - 'PWD': '/builds/slave/test', 09:57:38 INFO - 'SHELL': '/bin/bash', 09:57:38 INFO - 'SHLVL': '1', 09:57:38 INFO - 'TERM': 'linux', 09:57:38 INFO - 'TMOUT': '86400', 09:57:38 INFO - 'USER': 'cltbld', 09:57:38 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', 09:57:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:38 INFO - '_': '/tools/buildbot/bin/python'} 09:57:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:38 INFO - Downloading/unpacking psutil>=3.1.1 09:57: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 09:57: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 09:57: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 09:57: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 09:57:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:57:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:57:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:57:39 INFO - Installing collected packages: psutil 09:57:39 INFO - Running setup.py install for psutil 09:57:39 INFO - building 'psutil._psutil_linux' extension 09:57:39 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 09:57:39 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 09:57:39 INFO - building 'psutil._psutil_posix' extension 09:57:39 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 09:57:40 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 09:57:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:57:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:57:40 INFO - Successfully installed psutil 09:57:40 INFO - Cleaning up... 09:57:40 INFO - Return code: 0 09:57:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:57: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')]} 09:57:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57: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')]} 09:57:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1620d00>, '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 0x1621c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1622090>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', '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 09:57: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:57: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 mozsystemmonitor==0.3 09:57:40 INFO - Using env: {'DISPLAY': ':0', 09:57:40 INFO - 'HOME': '/home/cltbld', 09:57:40 INFO - 'LANG': 'en_US.UTF-8', 09:57:40 INFO - 'LANGUAGE': 'en_US:en', 09:57:40 INFO - 'LOGNAME': 'cltbld', 09:57:40 INFO - 'MAIL': '/var/mail/cltbld', 09:57:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:40 INFO - 'MOZ_NO_REMOTE': '1', 09:57:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:57:40 INFO - 'NO_EM_RESTART': '1', 09:57:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:57:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:40 INFO - 'PWD': '/builds/slave/test', 09:57:40 INFO - 'SHELL': '/bin/bash', 09:57:40 INFO - 'SHLVL': '1', 09:57:40 INFO - 'TERM': 'linux', 09:57:40 INFO - 'TMOUT': '86400', 09:57:40 INFO - 'USER': 'cltbld', 09:57:40 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', 09:57:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:40 INFO - '_': '/tools/buildbot/bin/python'} 09:57:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:57: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 09:57: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 09:57: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 09:57: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 09:57:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:57:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:57:41 INFO - Installing collected packages: mozsystemmonitor 09:57:41 INFO - Running setup.py install for mozsystemmonitor 09:57:41 INFO - Successfully installed mozsystemmonitor 09:57:41 INFO - Cleaning up... 09:57:41 INFO - Return code: 0 09:57:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:41 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 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1620d00>, '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 0x1621c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1622090>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', '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 09:57:41 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 09:57:41 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 09:57:41 INFO - Using env: {'DISPLAY': ':0', 09:57:41 INFO - 'HOME': '/home/cltbld', 09:57:41 INFO - 'LANG': 'en_US.UTF-8', 09:57:41 INFO - 'LANGUAGE': 'en_US:en', 09:57:41 INFO - 'LOGNAME': 'cltbld', 09:57:41 INFO - 'MAIL': '/var/mail/cltbld', 09:57:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:41 INFO - 'MOZ_NO_REMOTE': '1', 09:57:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:57:41 INFO - 'NO_EM_RESTART': '1', 09:57:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:57:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:41 INFO - 'PWD': '/builds/slave/test', 09:57:41 INFO - 'SHELL': '/bin/bash', 09:57:41 INFO - 'SHLVL': '1', 09:57:41 INFO - 'TERM': 'linux', 09:57:41 INFO - 'TMOUT': '86400', 09:57:41 INFO - 'USER': 'cltbld', 09:57:41 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', 09:57:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:41 INFO - '_': '/tools/buildbot/bin/python'} 09:57:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:41 INFO - Downloading/unpacking jsonschema==2.5.1 09:57: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 09:57: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 09:57: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 09:57: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 09:57:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:57:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:57:43 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 09:57:43 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 09:57:43 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 09:57:43 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 09:57:43 INFO - Downloading functools32-3.2.3-2.tar.gz 09:57:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:57:43 INFO - warning: no files found matching '*.txt' 09:57:43 INFO - no previously-included directories found matching 'build' 09:57:43 INFO - no previously-included directories found matching 'dist' 09:57:43 INFO - no previously-included directories found matching '.git*' 09:57:43 INFO - Installing collected packages: jsonschema, functools32 09:57:43 INFO - Running setup.py install for functools32 09:57:43 INFO - warning: no files found matching '*.txt' 09:57:43 INFO - no previously-included directories found matching 'build' 09:57:43 INFO - no previously-included directories found matching 'dist' 09:57:43 INFO - no previously-included directories found matching '.git*' 09:57:43 INFO - Successfully installed jsonschema functools32 09:57:43 INFO - Cleaning up... 09:57:43 INFO - Return code: 0 09:57:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:57: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')]} 09:57:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57: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')]} 09:57:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1620d00>, '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 0x1621c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1622090>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', '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 09:57:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:57:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:57:43 INFO - Using env: {'DISPLAY': ':0', 09:57:43 INFO - 'HOME': '/home/cltbld', 09:57:43 INFO - 'LANG': 'en_US.UTF-8', 09:57:43 INFO - 'LANGUAGE': 'en_US:en', 09:57:43 INFO - 'LOGNAME': 'cltbld', 09:57:43 INFO - 'MAIL': '/var/mail/cltbld', 09:57:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:43 INFO - 'MOZ_NO_REMOTE': '1', 09:57:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:57:43 INFO - 'NO_EM_RESTART': '1', 09:57:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:57:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:43 INFO - 'PWD': '/builds/slave/test', 09:57:43 INFO - 'SHELL': '/bin/bash', 09:57:43 INFO - 'SHLVL': '1', 09:57:43 INFO - 'TERM': 'linux', 09:57:43 INFO - 'TMOUT': '86400', 09:57:43 INFO - 'USER': 'cltbld', 09:57:43 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', 09:57:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:43 INFO - '_': '/tools/buildbot/bin/python'} 09:57:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:57:43 INFO - Cleaning up... 09:57:43 INFO - Return code: 0 09:57:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:57: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')]} 09:57:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57: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')]} 09:57:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1620d00>, '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 0x1621c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1622090>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', '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 09:57:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:57:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:57:43 INFO - Using env: {'DISPLAY': ':0', 09:57:43 INFO - 'HOME': '/home/cltbld', 09:57:43 INFO - 'LANG': 'en_US.UTF-8', 09:57:43 INFO - 'LANGUAGE': 'en_US:en', 09:57:43 INFO - 'LOGNAME': 'cltbld', 09:57:43 INFO - 'MAIL': '/var/mail/cltbld', 09:57:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:43 INFO - 'MOZ_NO_REMOTE': '1', 09:57:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:57:43 INFO - 'NO_EM_RESTART': '1', 09:57:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:57:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:43 INFO - 'PWD': '/builds/slave/test', 09:57:43 INFO - 'SHELL': '/bin/bash', 09:57:43 INFO - 'SHLVL': '1', 09:57:43 INFO - 'TERM': 'linux', 09:57:43 INFO - 'TMOUT': '86400', 09:57:43 INFO - 'USER': 'cltbld', 09:57:43 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', 09:57:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:43 INFO - '_': '/tools/buildbot/bin/python'} 09:57:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:43 INFO - Downloading/unpacking blobuploader==1.2.4 09:57:43 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 09:57:43 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 09:57:43 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 09:57:43 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 09:57:44 INFO - Downloading blobuploader-1.2.4.tar.gz 09:57:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:57:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:57:44 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 09:57:44 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 09:57:44 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 09:57:44 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 09:57:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:57:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:57:45 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 09:57:45 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 09:57:45 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 09:57:45 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 09:57:45 INFO - Downloading docopt-0.6.1.tar.gz 09:57:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:57:45 INFO - Installing collected packages: blobuploader, requests, docopt 09:57:45 INFO - Running setup.py install for blobuploader 09:57:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:57:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:57:45 INFO - Running setup.py install for requests 09:57:45 INFO - Running setup.py install for docopt 09:57:46 INFO - Successfully installed blobuploader requests docopt 09:57:46 INFO - Cleaning up... 09:57:46 INFO - Return code: 0 09:57:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:57: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')]} 09:57:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57: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')]} 09:57:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:46 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 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1620d00>, '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 0x1621c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1622090>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', '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 09:57:46 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 09:57:46 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 09:57:46 INFO - Using env: {'DISPLAY': ':0', 09:57:46 INFO - 'HOME': '/home/cltbld', 09:57:46 INFO - 'LANG': 'en_US.UTF-8', 09:57:46 INFO - 'LANGUAGE': 'en_US:en', 09:57:46 INFO - 'LOGNAME': 'cltbld', 09:57:46 INFO - 'MAIL': '/var/mail/cltbld', 09:57:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:46 INFO - 'MOZ_NO_REMOTE': '1', 09:57:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:57:46 INFO - 'NO_EM_RESTART': '1', 09:57:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:57:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:46 INFO - 'PWD': '/builds/slave/test', 09:57:46 INFO - 'SHELL': '/bin/bash', 09:57:46 INFO - 'SHLVL': '1', 09:57:46 INFO - 'TERM': 'linux', 09:57:46 INFO - 'TMOUT': '86400', 09:57:46 INFO - 'USER': 'cltbld', 09:57:46 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', 09:57:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:46 INFO - '_': '/tools/buildbot/bin/python'} 09:57:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:57:46 INFO - Running setup.py (path:/tmp/pip-xBDHpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:57:46 INFO - Running setup.py (path:/tmp/pip-rMBBoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:57:46 INFO - Running setup.py (path:/tmp/pip-Z3eKO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:57:46 INFO - Running setup.py (path:/tmp/pip-TeptqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:57:46 INFO - Running setup.py (path:/tmp/pip-tCOFgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:57:46 INFO - Running setup.py (path:/tmp/pip-haRRJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:57:46 INFO - Running setup.py (path:/tmp/pip-fAp7R2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:57:46 INFO - Running setup.py (path:/tmp/pip-43NGHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:57:46 INFO - Running setup.py (path:/tmp/pip-oCy0hN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:57:46 INFO - Running setup.py (path:/tmp/pip-2kvHnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:57:46 INFO - Running setup.py (path:/tmp/pip-JAPVtO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:57:47 INFO - Running setup.py (path:/tmp/pip-TQuwaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:57:47 INFO - Running setup.py (path:/tmp/pip-QQvBlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:57:47 INFO - Running setup.py (path:/tmp/pip-4KnJM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:57:47 INFO - Running setup.py (path:/tmp/pip-TIUdNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:57:47 INFO - Running setup.py (path:/tmp/pip-CI0l17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:57:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:57:47 INFO - Running setup.py (path:/tmp/pip-zu_JBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:57:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:57:47 INFO - Running setup.py install for manifestparser 09:57:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:57:47 INFO - Running setup.py install for mozcrash 09:57:47 INFO - Running setup.py install for mozdebug 09:57:47 INFO - Running setup.py install for mozdevice 09:57:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:57:47 INFO - Running setup.py install for mozfile 09:57:47 INFO - Running setup.py install for mozhttpd 09:57:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:57:48 INFO - Running setup.py install for mozinfo 09:57:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:57:48 INFO - Running setup.py install for mozInstall 09:57:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:57:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:57:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:57:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:57:48 INFO - Running setup.py install for mozleak 09:57:48 INFO - Running setup.py install for mozlog 09:57:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:57:48 INFO - Running setup.py install for moznetwork 09:57:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:57:48 INFO - Running setup.py install for mozprocess 09:57:48 INFO - Running setup.py install for mozprofile 09:57:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:57:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:57:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:57:48 INFO - Running setup.py install for mozrunner 09:57:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:57:48 INFO - Running setup.py install for mozscreenshot 09:57:49 INFO - Running setup.py install for moztest 09:57:49 INFO - Running setup.py install for mozversion 09:57:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:57:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:57:49 INFO - Cleaning up... 09:57:49 INFO - Return code: 0 09:57:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:57: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')]} 09:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57: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')]} 09:57:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:49 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 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1620d00>, '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 0x1621c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1622090>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', '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 09:57:49 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 09:57:49 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 09:57:49 INFO - Using env: {'DISPLAY': ':0', 09:57:49 INFO - 'HOME': '/home/cltbld', 09:57:49 INFO - 'LANG': 'en_US.UTF-8', 09:57:49 INFO - 'LANGUAGE': 'en_US:en', 09:57:49 INFO - 'LOGNAME': 'cltbld', 09:57:49 INFO - 'MAIL': '/var/mail/cltbld', 09:57:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:49 INFO - 'MOZ_NO_REMOTE': '1', 09:57:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:57:49 INFO - 'NO_EM_RESTART': '1', 09:57:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:57:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:49 INFO - 'PWD': '/builds/slave/test', 09:57:49 INFO - 'SHELL': '/bin/bash', 09:57:49 INFO - 'SHLVL': '1', 09:57:49 INFO - 'TERM': 'linux', 09:57:49 INFO - 'TMOUT': '86400', 09:57:49 INFO - 'USER': 'cltbld', 09:57:49 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', 09:57:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:49 INFO - '_': '/tools/buildbot/bin/python'} 09:57:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:57:49 INFO - Running setup.py (path:/tmp/pip-Kk22SS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:57:49 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)) 09:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:57:49 INFO - Running setup.py (path:/tmp/pip-goTLoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:57:49 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)) 09:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:57:49 INFO - Running setup.py (path:/tmp/pip-a8D6rB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:57:49 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)) 09:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:57:49 INFO - Running setup.py (path:/tmp/pip-V7oU1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:57:49 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)) 09:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:57:49 INFO - Running setup.py (path:/tmp/pip-Jt4b7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:57:49 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)) 09:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:57:49 INFO - Running setup.py (path:/tmp/pip-5dkelc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:57:49 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)) 09:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:57:49 INFO - Running setup.py (path:/tmp/pip-_hjySw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:57:49 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)) 09:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:57:49 INFO - Running setup.py (path:/tmp/pip-49iQiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:57:50 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)) 09:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:57:50 INFO - Running setup.py (path:/tmp/pip-6ZKbXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:57:50 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)) 09:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:57:50 INFO - Running setup.py (path:/tmp/pip-ygTGuZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:57:50 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)) 09:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:57:50 INFO - Running setup.py (path:/tmp/pip-P1Bb5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:57:50 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)) 09:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:57:50 INFO - Running setup.py (path:/tmp/pip-I03xND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:57:50 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)) 09:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:57:50 INFO - Running setup.py (path:/tmp/pip-gkzljY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:57:50 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)) 09:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:57:50 INFO - Running setup.py (path:/tmp/pip-1rx_Al-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:57:50 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)) 09:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:57:50 INFO - Running setup.py (path:/tmp/pip-v7oHNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:57:50 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)) 09:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:57:50 INFO - Running setup.py (path:/tmp/pip-riNpnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:57:50 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)) 09:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:57:50 INFO - Running setup.py (path:/tmp/pip-T0jDVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:57:50 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)) 09:57:50 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)) 09:57:50 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)) 09:57:50 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)) 09:57:50 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)) 09:57:50 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)) 09:57:50 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)) 09:57:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:57: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 09:57: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 09:57: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 09:57: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 09:57:51 INFO - Downloading blessings-1.6.tar.gz 09:57:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:57:51 INFO - Installing collected packages: blessings 09:57:51 INFO - Running setup.py install for blessings 09:57:51 INFO - Successfully installed blessings 09:57:51 INFO - Cleaning up... 09:57:52 INFO - Return code: 0 09:57:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:57:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:57:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:57:52 INFO - Reading from file tmpfile_stdout 09:57:52 INFO - Current package versions: 09:57:52 INFO - argparse == 1.2.1 09:57:52 INFO - blessings == 1.6 09:57:52 INFO - blobuploader == 1.2.4 09:57:52 INFO - docopt == 0.6.1 09:57:52 INFO - functools32 == 3.2.3-2 09:57:52 INFO - jsonschema == 2.5.1 09:57:52 INFO - manifestparser == 1.1 09:57:52 INFO - mozInstall == 1.13 09:57:52 INFO - mozcrash == 1.0 09:57:52 INFO - mozdebug == 0.1 09:57:52 INFO - mozdevice == 0.51 09:57:52 INFO - mozfile == 1.2 09:57:52 INFO - mozhttpd == 0.7 09:57:52 INFO - mozinfo == 0.10 09:57:52 INFO - mozleak == 0.1 09:57:52 INFO - mozlog == 3.5 09:57:52 INFO - moznetwork == 0.27 09:57:52 INFO - mozprocess == 0.25 09:57:52 INFO - mozprofile == 0.28 09:57:52 INFO - mozrunner == 6.13 09:57:52 INFO - mozscreenshot == 0.1 09:57:52 INFO - mozsystemmonitor == 0.3 09:57:52 INFO - moztest == 0.8 09:57:52 INFO - mozversion == 1.4 09:57:52 INFO - psutil == 3.1.1 09:57:52 INFO - requests == 1.2.3 09:57:52 INFO - wsgiref == 0.1.2 09:57:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:57: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')]} 09:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57: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')]} 09:57:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:52 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 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1620d00>, '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 0x1621c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1622090>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', '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 09:57:52 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 09:57:52 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 09:57:52 INFO - Using env: {'DISPLAY': ':0', 09:57:52 INFO - 'HOME': '/home/cltbld', 09:57:52 INFO - 'LANG': 'en_US.UTF-8', 09:57:52 INFO - 'LANGUAGE': 'en_US:en', 09:57:52 INFO - 'LOGNAME': 'cltbld', 09:57:52 INFO - 'MAIL': '/var/mail/cltbld', 09:57:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:52 INFO - 'MOZ_NO_REMOTE': '1', 09:57:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:57:52 INFO - 'NO_EM_RESTART': '1', 09:57:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:57:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:52 INFO - 'PWD': '/builds/slave/test', 09:57:52 INFO - 'SHELL': '/bin/bash', 09:57:52 INFO - 'SHLVL': '1', 09:57:52 INFO - 'TERM': 'linux', 09:57:52 INFO - 'TMOUT': '86400', 09:57:52 INFO - 'USER': 'cltbld', 09:57:52 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', 09:57:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:52 INFO - '_': '/tools/buildbot/bin/python'} 09:57:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:52 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)) 09:57:52 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)) 09:57:52 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)) 09:57:52 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)) 09:57:52 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)) 09:57:52 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)) 09:57:52 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)) 09:57:52 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)) 09:57:52 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)) 09:57:52 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:53 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:57:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:57:53 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 09:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 09:57:53 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)) 09:57:53 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)) 09:57:53 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)) 09:57:53 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)) 09:57:53 INFO - Installing collected packages: marionette-driver, simplejson 09:57:53 INFO - Running setup.py install for marionette-driver 09:57:53 INFO - Running setup.py install for simplejson 09:57:54 INFO - building 'simplejson._speedups' extension 09:57:54 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 09:57:54 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 09:57:54 INFO - Successfully installed marionette-driver simplejson 09:57:54 INFO - Cleaning up... 09:57:55 INFO - Return code: 0 09:57:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:55 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 0x12b21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x147ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1623b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1620d00>, '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 0x1621c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1622090>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', '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 09:57:55 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 09:57:55 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 09:57:55 INFO - Using env: {'DISPLAY': ':0', 09:57:55 INFO - 'HOME': '/home/cltbld', 09:57:55 INFO - 'LANG': 'en_US.UTF-8', 09:57:55 INFO - 'LANGUAGE': 'en_US:en', 09:57:55 INFO - 'LOGNAME': 'cltbld', 09:57:55 INFO - 'MAIL': '/var/mail/cltbld', 09:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:55 INFO - 'MOZ_NO_REMOTE': '1', 09:57:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:57:55 INFO - 'NO_EM_RESTART': '1', 09:57:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:55 INFO - 'PWD': '/builds/slave/test', 09:57:55 INFO - 'SHELL': '/bin/bash', 09:57:55 INFO - 'SHLVL': '1', 09:57:55 INFO - 'TERM': 'linux', 09:57:55 INFO - 'TMOUT': '86400', 09:57:55 INFO - 'USER': 'cltbld', 09:57:55 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', 09:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:55 INFO - '_': '/tools/buildbot/bin/python'} 09:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:57:55 INFO - Cleaning up... 09:57:55 INFO - Return code: 0 09:57:55 INFO - Running post-action listener: _resource_record_post_action 09:57:55 INFO - Running post-action listener: _start_resource_monitoring 09:57:55 INFO - Starting resource monitoring. 09:57:55 INFO - [mozharness: 2017-07-18 16:57:55.298336Z] Finished create-virtualenv step (success) 09:57:55 INFO - [mozharness: 2017-07-18 16:57:55.298847Z] Running install step. 09:57:55 INFO - Running pre-action listener: _resource_record_pre_action 09:57:55 INFO - Running main action method: install 09:57:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:57:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:57:55 INFO - Reading from file tmpfile_stdout 09:57:55 INFO - Detecting whether we're running mozinstall >=1.0... 09:57:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:57:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:57:55 INFO - Reading from file tmpfile_stdout 09:57:55 INFO - Output received: 09:57:55 INFO - Usage: mozinstall [options] installer 09:57:55 INFO - Options: 09:57:55 INFO - -h, --help show this help message and exit 09:57:55 INFO - -d DEST, --destination=DEST 09:57:55 INFO - Directory to install application into. [default: 09:57:55 INFO - "/builds/slave/test"] 09:57:55 INFO - --app=APP Application being installed. [default: firefox] 09:57:55 INFO - mkdir: /builds/slave/test/build/application 09:57:55 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'] 09:57:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:58:13 INFO - Reading from file tmpfile_stdout 09:58:13 INFO - Output received: 09:58:13 INFO - /builds/slave/test/build/application/firefox/firefox 09:58:13 INFO - Running post-action listener: _resource_record_post_action 09:58:13 INFO - [mozharness: 2017-07-18 16:58:13.556853Z] Finished install step (success) 09:58:13 INFO - [mozharness: 2017-07-18 16:58:13.557195Z] Running setup-mitmproxy step. 09:58:13 INFO - Running pre-action listener: _resource_record_pre_action 09:58:13 INFO - Running main action method: setup_mitmproxy 09:58:13 INFO - Skipping: mitmproxy is not required 09:58:13 INFO - Running post-action listener: _resource_record_post_action 09:58:13 INFO - [mozharness: 2017-07-18 16:58:13.558883Z] Finished setup-mitmproxy step (success) 09:58:13 INFO - [mozharness: 2017-07-18 16:58:13.559190Z] Running run-tests step. 09:58:13 INFO - Running pre-action listener: _resource_record_pre_action 09:58:13 INFO - Running pre-action listener: _set_gcov_prefix 09:58:13 INFO - Running main action method: run_tests 09:58:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:58:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:58:13 INFO - Python 2.7.3 09:58:13 INFO - Return code: 0 09:58:13 INFO - grabbing minidump binary from tooltool 09:58:13 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')]} 09:58:13 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 0x1620d00>, '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 0x1621c00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1622090>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:58:13 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 09:58:13 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 09:58:13 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 09:58:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:58:13 INFO - Return code: 0 09:58:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:58:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:58:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:58:13 INFO - ENV: RUST_BACKTRACE is now 1 09:58:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:58:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:58:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 09:58:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 09:58:13 INFO - Using env: {'DISPLAY': ':0', 09:58:13 INFO - 'HOME': '/home/cltbld', 09:58:13 INFO - 'LANG': 'en_US.UTF-8', 09:58:13 INFO - 'LANGUAGE': 'en_US:en', 09:58:13 INFO - 'LOGNAME': 'cltbld', 09:58:13 INFO - 'MAIL': '/var/mail/cltbld', 09:58:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:58:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:58:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:13 INFO - 'MOZ_NO_REMOTE': '1', 09:58:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:58:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:13 INFO - 'NO_EM_RESTART': '1', 09:58:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:13 INFO - 'PWD': '/builds/slave/test', 09:58:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:58:13 INFO - 'RUST_BACKTRACE': '1', 09:58:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:58:13 INFO - 'SHELL': '/bin/bash', 09:58:13 INFO - 'SHLVL': '1', 09:58:13 INFO - 'TERM': 'linux', 09:58:13 INFO - 'TMOUT': '86400', 09:58:13 INFO - 'USER': 'cltbld', 09:58:13 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806', 09:58:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:13 INFO - '_': '/tools/buildbot/bin/python'} 09:58:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 09:58:13 INFO - mozversion application_buildid: 20170718161501 09:58:13 INFO - mozversion application_changeset: 56c6c81993e6b0f0e094e0c88b42ce3ec566ead9 09:58:13 INFO - mozversion application_display_name: Nightly 09:58:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:58:13 INFO - mozversion application_name: Firefox 09:58:13 INFO - mozversion application_remotingname: firefox 09:58:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:58:13 INFO - mozversion application_vendor: Mozilla 09:58:13 INFO - mozversion application_version: 56.0a1 09:58:13 INFO - mozversion platform_buildid: 20170718161501 09:58:13 INFO - mozversion platform_changeset: 56c6c81993e6b0f0e094e0c88b42ce3ec566ead9 09:58:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:58:13 INFO - mozversion platform_version: 56.0a1 09:58:13 INFO - using testdate: 1500397093 09:58:13 INFO - actual date: 1500397093 09:58:13 INFO - starting webserver on 'localhost:40530' 09:58:13 INFO - SUITE-START | Running 2 tests 09:58:13 INFO - TEST-START | dromaeo_css 09:58:13 INFO - Initialising browser for dromaeo_css test... 09:58:13 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40530/getInfo.html -profile /tmp/tmp8Ezb3S/profile 09:58:13 INFO - TEST-INFO | started process 11940 (/builds/slave/test/build/application/firefox/firefox http://localhost:40530/getInfo.html) 09:58:19 INFO - TEST-INFO | 11940: exit 0 09:58:20 INFO - Browser initialized. 09:58:20 INFO - Running cycle 1/1 for dromaeo_css test... 09:58:20 INFO - TEST-INFO | started process 12211 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8Ezb3S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:58:31 INFO - PID 12211 | RSS: Main: 175988736 09:58:31 INFO - PID 12211 | 10:01:29 INFO - PID 12211 | Cycle 1(1): loaded http://localhost:40530/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40530/tests/dromaeo/cssquery-ext.html) 10:01:31 INFO - PID 12211 | RSS: Main: 177655808 10:01:31 INFO - PID 12211 | 10:04:33 INFO - PID 12211 | Cycle 1(1): loaded http://localhost:40530/tests/dromaeo/cssquery-ext.html (next: http://localhost:40530/tests/dromaeo/cssquery-jquery.html) 10:04:33 INFO - PID 12211 | RSS: Main: 177688576 10:04:33 INFO - PID 12211 | 10:07:35 INFO - PID 12211 | Cycle 1(1): loaded http://localhost:40530/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40530/tests/dromaeo/cssquery-mootools.html) 10:07:35 INFO - PID 12211 | RSS: Main: 180256768 10:07:35 INFO - PID 12211 | 10:10:37 INFO - PID 12211 | Cycle 1(1): loaded http://localhost:40530/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40530/tests/dromaeo/cssquery-prototype.html) 10:10:38 INFO - PID 12211 | RSS: Main: 177250304 10:10:38 INFO - PID 12211 | 10:13:41 INFO - PID 12211 | Cycle 1(1): loaded http://localhost:40530/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40530/tests/dromaeo/cssquery-yui.html) 10:13:42 INFO - PID 12211 | RSS: Main: 180719616 10:13:42 INFO - PID 12211 | 10:16:43 INFO - PID 12211 | Cycle 1(1): loaded http://localhost:40530/tests/dromaeo/cssquery-yui.html (next: http://localhost:40530/tests/dromaeo/cssquery-dojo.html) 10:16:44 INFO - PID 12211 | RSS: Main: 182706176 10:16:44 INFO - PID 12211 | 10:16:44 INFO - PID 12211 | __start_tp_report 10:16:44 INFO - PID 12211 | _x_x_mozilla_page_load 10:16:44 INFO - PID 12211 | _x_x_mozilla_page_load_details 10:16:44 INFO - PID 12211 | |i|pagename|runs| 10:16:44 INFO - PID 12211 | |0;dojo.html;3078.0458058002023;3080.907572772815;3121.2040929562963;3140.4190224808412;3166.7466602671775;6052.42501962314;6076.149339092525;6140.048292514667;6150.969245153774;6179.949408600537;6144.477719393845;6148.262208534982;6171.074338849164;6172.074188871671;6180.227471566054;5625.1843482694985;5667.773289068443;5673.716314184295;5686.317641882959;5699.829005129832;5662.631928924607;5667.064934285859;5668.773249070032;5672.68800215988;5675.801346952858;5692.0608099663505;5695.743691533891;5698.943010569903;5704.172894930232;5707.258056452676;5687.374388817227;5687.601867869251;5697.0315046442;5698.31620205578;5713.171590119409;5571.9721401392735;5603.579731520112;5620.9437905621235;5655.660660360391;5714.51426628737;6081.270247570275;6089.634621922654;6114.938850611481;6133.386661333876;6179.412955769195;6439.291677915426;6477.514186436036;6507.743170349757;6534;6563.474922006228;6500.187476565429;6575.671216439207;6586.27550969393;6594.604323740543;6596.571222870546;6191.380861913818;6192.256929168529;6204.224471941007;6234.625922444668;6239.688015599248;6790.1851777786;6791.796246112624;6859.279775623588;6869.931300686929;6884.552504087218;6253.53098517613;6486.772962946275;6537.313582073909;6555.311692272243;6566.573172743756;6391.872162556723;6539.673016349164;6550.836229094215;6653.600783952978;6660.766873159513;6495.3179916109075;6498.740050397932;6526.902096468557;6617.7683781067435;6632.137822083098;6483.092367068614;6537.836554086186;6544.476441884638;6599.142111525471;6630.734770609218;5222.451642577475;5282.709450980233;5343.3587969443915;5356.196570514376;5364.061289274361;4535.614472769851;4546.4544254689645;4548.7043342183315;4548.977255113769;4581.541845815392;5739;5750.482456578928;5754.539636829044;5766.135079737989;5766.365699773021;6624.503162262849;6627.900581491282;6633.170853643294;6645.401913827779;6660.234073081658;5655.773769049192;5700.116481945305;5770.769169233267;5777.19119323295;5791.9710401448565;5847.298324201123;5848.385919478478;5852.180694702745;5853.970730146322;5861.589688721834;6470.805875823733;6482.773102941469;6483.124778154983;6485.4811615070685;6492.220933487917;6576.408123268929;6680.365365290289;6705.731770729311;6713.496487763535;6718.428933540704;6377.8724425512155;6385.616862988236;6403.007533832263;6459.128017717736;6485.708143133665;6328;6422.486201103994;6449;6468.062130991074;6538.738450461929;5503.752331145028;6489.091527186103;6550.082988381535;6654.667266636746;6672.399484046459;6365.172527571386;6382.904256436064;6462.224533055875;6471.964485682268;6504.869902601827;6366.9044964326495;6413.839654008687;6481.287058423664;6490.3185165558825;6506.056621789719;6257.374262573706;6318.462930650762;6356.396142366468;6360.459360954371;6362.236531616236;6388.680565971776;6436.710348034256;6443.162388889415;6444.9355506444335;6460.903086453801;6165.753369865155;6201.441870231701;6239.937600623935;6250.062490626442;6302.117703521419 10:16:44 INFO - PID 12211 | |1;ext.html;729.4346881167095;780.2275747010459;804.4127786715699;806.2219957740779;1174.6828356343783;30386.392272154542;31012.53481197781;39736.80131599341;40785.59214407855;40841.18317633645;41483.17033659325;41913.790431047804;41974.58025419745;41992;42013.159736805246;7619.390448764113;7730;7733.9226607734045;7766.961165194166;7767.572783496906;7182.928170718278;7603.239676032408;7703.191164927686;7705.845883082335;7717.922820771804;7317.524360916524;7724.42066844986;7727.459077864552;7737.148913619498;7749.225077492262;7682.807929801744;7749.961250193769;7754.495957762784;7757.146713861471;7774.883376749353;7682.7311044113185;7688.500247483896;7688.5386876787015;7690.653920573532;7745.728899488491;38567.80716096402;38702;38869;38920.41619375712;38967.41548876769;3552.5381700379044;3582.960941327012;3585.94621080684;3591.2278860044953;3594.4428613564937;30716.846415768003;30737.692623073708;30750.53874191889;30847.53728694049;30924.690753092407;4656.837010704609;4786.922942337981;4897.632677549198;4916.827911023097;4936.531029552187;202148;202574;202650;202683;203286;48636.5136348632;48837;48859;48929.755351223015;48953;50224.497755021985;50496;50676.74661626668;50738.23892641613;50759;15835.524934251991;16342.75485867713;16369.508914732578;16405.42581009659;16422.917885410498;13378.598642040753;13395.12931659439;13406.329683515785;13410.262435566137;13413.39639716215;12718.173318734252;14501.419943202365;14517.78223326651;14635.195064271355;14661;5216.21756736485;5404.189371594213;5405.054115529767;5412.594055445778;5510.559155267649;973.2943615878431;988.3427520698799;1000.46475135802;1020.4081632653043;1021.918246540275;22789.430264243194;22848.623672050617;23002.079916803475;23246.76753232446;23831.404214894766;1078.2398409121552;1101.2676570080966;1110.4891749795024;1115.587232723881;1132.1961407400838;11806;12207.450664720109;12212.877871221352;12242.44908979098;12465.688357790947;226272;231370;231386;231712;232482;54741.178882316795;55002.44997549973;55301.72349138228;55309.170362444595;55331.44668553343;1467.149053548939;1491.7016596680708;1506.6760646461064;1508.2609521334466;1510.9546713598609;26196.345091372867;27022.189334320003;27245.72754272511;27346.726532735214;27521.31196720098;38097.23805523819;38250;38367.42448863213;38367.808160959015;38428;201622;201658;201837;202519;202671;33684;34554.65445345514;34753.47869782006;34767.30465390729;34878.65121348754;40042.599574003885;41830.581694183886;41851.7907410461;41975.58024419839;42561.361579575714;29720.554191686708;30513.38973220479;30622.234444139074;30692.386152276384;30768.538471923388;30049.549256761602;31492.212694682818;31517.212069697525;31530.211744706565;31947;34210.48684269688;34502.30995380128;34504.82747586246;38128.809355953046;38856.6114338853;32009.8399508001;32487;33051.504227436126;33505.83247083749;33619.12522684246;24166.758332417154;24473.877630611732;24933;25381.365465863502;25392.849122981963 10:16:44 INFO - PID 12211 | |2;jquery.html;552.825859854146;563.5041163775877;563.8421242052227;564.2016546586581;579.6985567504897;21369.572608547838;22250.221242256524;22419.887900560476;22422.55154896903;22496.32511024668;25595.872020639916;26204.344891377725;26286.342841428974;26359.47281054378;26367.3408164796;6327.810165695025;6427.93572064278;6469.288378278386;6503.349665033506;6508.47932165428;6120.571559990803;6328.525360597951;6386.2336519617475;6408.070829729686;6420.325865784095;6164.506839452833;6315.810525684214;6365.267994180682;6403.231612206541;6435.388638079375;6381.968090159519;6386;6391.424771770539;6391.6804159792055;6415.0377443383395;5783.566232532577;5823.79616713413;5828.358880523181;5836.416358364172;5906.084556893688;26885;27215.591766123525;27793.444131117263;28172.577411338847;28205;1900.3633782682818;1910.1977169588667;1928.469670840516;1944.562473443478;1948.7953764854628;18048.368307109187;18513.352032678926;18732.250709971584;18746.718799218022;18770.061496925235;2302.3553405046614;2335.0309621507054;2407.5305315463493;2413.6017557103055;2415.9396015099587;128798;132594;133033;133121;138486.30756846152;13728.725425491435;14178.85821141796;14219.36012879423;14678;15137.394504219928;22781.31656050321;22798.43003924882;22805.885970570373;22823.429414264443;22931;10208.540615672313;10370.170386369073;10462.947685261524;10517.789644207072;10609.363438193734;9832.164266037467;9873.358231714916;9877.11106000449;9945.80108397828;10105.848412273821;7690.500117492345;8884;8934.86597701035;8998.280137588978;9153.725388238363;2623.1737002844043;2664.454616322528;2724.6185534024858;2734.2890848379566;2750.119961612265;945.2721404518483;950.8573713942826;958.5015791788231;984.6701355045997;1002.3735165521549;19487;19581.216751329786;20168.798312016985;23211.53576928452;25853.870730646602;1041.6093964763213;1053.7154968158707;1068.6793961811425;1071.6945670483874;1075.5321435175724;11221.94389028066;11228.719282017852;11343.319400835977;11370.431478426046;11604.329973870847;492589;498080;513335;517183;519276;113382;122067;123567.38216308861;124128;126526.36736816257;1288.3365066990507;1290.7741145299535;1294.5274974634378;1331.1281110872394;1339.270097796703;18620.906895465436;21218.151273949486;21280.680789788476;21649.783502164777;21768.782312176674;56184;57831.71084144552;58796.706016469645;59004.1149382751;60729.089063664964;76959.23040769521;76975.2302476968;77198.61400692961;77756;78089;18751.437456876316;20486.282980095624;20535.589288213854;20624.381268561967;20749.1700331985;22882.08471661115;22893.65659515142;23207.767922321236;23350.416239593465;23374.415639609146;18085.728714069563;18282.268709251483;18382.816171838644;18507.722384164517;18691;15557.45548905783;15601.921990389976;16706.665866682357;16877;16921.576960575592;18918.527036823638;19167;19240.615187696447;19281.710774338117;19290.807091929462;19141.712874307173;20145.19419223273;20210.494737631678;20241.493962651053;20313.898430507754;17871.106444677975;18210.817891821443;18306.90846545759;18428.81571184271;18478.81521184771 10:16:44 INFO - PID 12211 | |3;mootools.html;1333.346660136533;1385.5150697255963;1393.3033483258364;1396.0437100586093;1397.9860201397987;13197.208167509956;13897.305134743261;13926.930365348158;13933.860661393395;13980.510682126127;13563.45746170152;13954.232517211549;14367.92816035921;14434.06178598393;14440.855591444082;2534.3410713214603;2580.870956452179;2587.922362329133;2595.221433569931;2604.7525485078886;2577.059373328731;2611.9085831995885;2626.579747240442;2628.986855065722;2630.8947642094295;2503.4492411669494;2541.224926397448;2579.638850560923;2589.223233030093;2592.5333439980795;2576.4331846993723;2658.734126587345;2678.2099280712237;2684.2349930269975;2686.7850571954295;2579.6904371475543;2593.3775893785546;2593.6757905261843;2595.1046888823325;2601.2456387647558;13674.794878076837;13805.17168969865;13808.1024733392;13837;13863.930680346635;7318.81702957425;7567.432442566829;7603.619819009083;7615.923840761632;7649.579273140031;4626.444826620827;4831.299461578052;4922;4929.260610908356;4972.279019542182;1808.5478630342413;1812.4290848382718;1817.5365281853176;1866.2161892005254;1873.2694249242807;22730.659040114413;22973.195938142086;22987.42531436711;22994.310170694906;23002.884985574965;5992.400759923973;6028.9397106029255;6132.908006379907;6239.719212635442;6289.55973081889;14842.257887105601;14843.554693359001;14887.553373398816;14893.47872824446;14898.702025959421;6800.6939687714175;6879.552829066095;6887.965560172267;6897.965510172417;6910.723571057101;6061.302950160788;6257.155284036597;6259.3427690091885;6313.337099604567;6365.822322870284;4694.976525117421;4818.397700287464;4875.658703890765;4890.975545122252;4905.092557876733;1354.688421663023;1356.1456282541935;1380.5444203412849;1380.6824430381068;1387.8126452928927;884.186548375496;892.3797960417577;907.9500627465553;924.8196601662746;936.9437833729997;7215.098112735908;7235.240299768554;7274;7308.196098429168;7366.668499917517;679.7858674517511;683.4225079807654;686.4234043403466;691.7060249393987;692.6675195905892;4411.2721400968485;4725.740084295328;4778.402699662543;5073.695578265316;5186.714730689849;17812.376566820098;18137.455876323733;18989.525261868286;18989.525261868286;18992.52518687044;18395.90802045981;18415.81584184141;18820.24719011224;18946.24215031411;19048.238070477026;10681;10868.619598314179;10903.672889813319;10965.725856853642;11001;7753.65108570127;7947.125816160102;8051.2351326623875;8052.677892884453;8163.387745918961;15988.600284992968;16344.918275408547;16394.098324591785;16734.4142954996;16802.411915582896;15674.373025078869;16128.67742645164;17044.659106817548;17273.309067637154;17466.039367834892;10055.39667619945;10577.206709496972;10663.253572250178;10668.89331106679;10841.915808419095;12778.74442511126;12910.289934053342;12980.091393602357;12981.156224845356;13049.34753262352;10301.845472317771;10618.469076546296;10732.14142868553;10732.73168170802;10948.39783811866;10045.397276163454;10362.067413932782;10435.530401132119;10455.059044685711;10512.789744205225;9176.495292758962;9300.39547429442;9401.952990235006;9642.517874106126;9706.805863882542;6382.648954307558;6416.3004394725585;6664.566803157779;6742.6628668567355;6860.211075726355;7167.856642867009;7342.632868356454;7403.555786652818;7462.888056679263;7481.7755467336065 10:16:44 INFO - PID 12211 | |4;prototype.html;464.697424262778;473.7986355798787;502.63307785316715;505.6713136461301;507.9136546787048;5756.366799652045;5899.410058994093;5946.970265148673;5972.88054238915;6034.155218269435;5767.884642307151;5821.970890145554;5925.4963328117055;5938.52491800656;5994;4780.641451891105;4783.569478746912;4810.7113573185725;4858.271259311114;4865.391826021747;4797.808087676506;4856.761525810914;4870.975645121769;4877.804887804484;4878.048780487807;4756.9286460703115;4762.571088744748;4805.20714082176;4810.97594512027;4844.224924012159;4548.090381923612;4700.27145792401;4781.187198176319;4793.3768610080815;4851.51484851512;4322.724796185133;4327.415798867144;4334.479862416499;4349.41282926804;4363.105563359535;5788.82633520995;5864.354920958691;5893.292804863401;5902.32123305821;5912.073523605843;5496.615236933416;5509.47659972302;5527.115661494142;5544.805931792368;5555.2778138841695;6315.715792789336;6323.008252400525;6325.683715814237;6399.168108145911;6404.615723056585;4732.408448943882;4738.691985020962;4738.763061846894;4743.383360163157;4748.5251474852585;6663.267040625527;6681.599104053724;6729.1252137221845;6755.973208037569;6771.119754431892;4845.176320025604;4846.80612775492;4855.781489832966;4856.587190088882;4875.317455556225;4823.541763532458;4869.92861570461;4889.902201955941;4895.975520122377;4930.506949305112;5408.675479471244;5435.510804027577;5435.646682965595;5489.560835133179;5499.120140777536;6384.553081284358;6403.615783052938;6416.871662566722;6428;6433.195850518685;1677.2620047179203;1684.7388654758531;1685.3006002509067;1686.5530634381898;1694.2883988724766;1078.2314616800056;1160.0951258018758;1172.9882701173049;1190.660661711408;1191.463841271414;5420.972895135499;5513.117901135799;5570.192322113271;5602.943970560324;5680.14797780328;6358.523110766711;6469.7918175867935;6526.878764401588;6552.737890484327;6564.932046405367;5605.635633683797;5680.687562184037;5715.685637289906;5753.33836608787;5763.135529670583;3410.334984677964;3425.72594192469;3436.4845273209216;3454;3461.5596004816543;6237.906431403442;6251.999040460604;6296.307406185316;6346.30190679025;6370.490360771128;6653.800385988428;6669.166354205724;6694.849193500061;6721.5967041976655;6771.28901465339;6255.374462553708;6298.433141017331;6306.810795676137;6337.746490140435;6338.58799178052;6411.166548348685;6510.251321098134;6552.115464412243;6561.9500879859;6567.671616419065;6379.213820130371;6396.200474940632;6421.678916054272;6524.021396790329;6543.1821022372205;6244.687765611792;6254.2807577129215;6287.465565426807;6287.893555658906;6337.302896681269;4711.458182308941;4724.787384567634;4743.952560474351;4749.548792864673;4753.120672675566;6513.609183449008;6514.837129071811;6516.73933042692;6523.739050437929;6547.214334279917;6275.717592708431;6284.366064823087;6336.1446204763615;6348.365163483615;6363.204599425072;6259.96870015647;6354.122436746084;6365.7135428904885;6369.490440764635;6397.488200943821;6229.844253893508;6260.40526150015;6261.24865016201;6310.211223597051;6318.494520438262;6221.3156552778255;6236.438720514994;6267.874642507216;6324.588901721373;6340.524460665376;5633.802816901389;6049.727762250797;6101.572889897665;6153.784617538365;6226.696122434728 10:16:44 INFO - PID 12211 | |5;yui.html;898.5327629632591;920.0339643374455;921.9216366608838;924.579316411033;929.8140371925616;16670.58323541912;18213.271469141237;18760;18826.905865470686;18941.90529047356;15941.601459963478;16177.19114044296;18658.906705466452;19586.11862466189;20846.166153353886;1000.8940121166116;1023.1303392116679;1025.5026312238558;1026.1021606094666;1032.9173666106694;1023.8310678738;1027.4708525109575;1029.1303848248256;1038.376973815712;1041.4584416103621;772.5712229712507;1047.9266451348408;1092.956281748729;1093.3658478082732;1093.8031154392206;1072.2333531524957;1073.6456969200144;1079.3901445683164;1081.7944590527775;1082.967510974672;793.821390187209;794.047143427883;794.4002278279862;795.026093036032;795.6658203554521;18489.352872649393;18564.721529177074;18890.905545472186;18963.146658400266;19535.120919558653;17241.741373879402;17263.74104388435;17610.559736006573;17662.470125896245;17739.46781596554;15920.203989800579;15950.202489875459;15969.36122555108;16052.357905683759;16076.59808504785;3387.0685561470546;3392.694657480814;3450.4996775467666;3454.360943225486;3462.23830757233;128910;129718;134783;135907;136324;15568.299426525833;15634.921825391028;15923.124228167562;15983.600409989844;16196.514104576881;30098.398032039237;31405;31453.37093258122;31535;31584.36831263362;8486;8656.09111043344;8733.737987860375;8794.384393092421;8853.247473964786;4201.6008479194425;4246.766427846436;4272.893177670521;4298.505671847715;4304.074623955865;4843.757812109451;5012.373453318335;5060.2156665716875;5078.289039534468;5085.542301192911;891.9375643705008;929.1266209762802;933.0622724162174;1046.0794500839365;1070.5771220368067;626.786892456567;688.198249039873;694.7603076938448;703.0930100170825;708.1360739897317;11215.71960700989;11298.717532061764;11707.2975621463;11939.641810745692;12336.75326493465;664.7008846019311;685.5715178013742;687.594319351585;696.5785699651739;700.8948657701386;5120.820771273062;5273.709945952933;5277.419483856773;5336.306280183551;5490.670559766427;97641.0235897632;100115;102826.48586757018;106788;107643;94192;97334;98227.50886245523;98824.50587747015;98946.5052674732;76896.61551692318;77722.2227777715;80084;81521.77717334245;83962;10261.743456413351;10371.429571373646;10474.790504190027;10705.625303114664;10853.240273180802;33201.169970750925;33339.49990750092;35250.823745881105;35906.64093359033;36469;44995;46131;46534.30198547092;64891.67554162199;65155.674221628586;17562.736558951372;18509.444716658523;18866.622667546297;19086.713699294243;19403.902980485007;19993.400197994084;20488.38534843957;21453.249136280156;21761.891190543945;21783.455413614152;15396.84603153954;17119;17725.822741772416;19241.4227573173;19643.70534442013;17695.203715832562;17903.10484475745;19151.13819878081;19156.23375064982;19737.40787776369;18453;18679.81320186835;19105.522361941064;19115.904420477807;19450.124744386252;12895.613131606067;13734.45062197501;14287.42850285977;14443.133411995314;14516.491922783074;13926;14278.571642850731;14324.14055156694;14364.569062928129;14496.927515362355 10:16:44 INFO - PID 12211 | __end_tp_report 10:16:44 INFO - PID 12211 | __start_cc_report 10:16:44 INFO - PID 12211 | _x_x_mozilla_cycle_collect,369 10:16:44 INFO - PID 12211 | __end_cc_report 10:16:44 INFO - PID 12211 | __startTimestamp1500398204003__endTimestamp 10:16:44 INFO - PID 12211 | 10:16:44 INFO - PID 12211 | ------- Summary: start ------- 10:16:44 INFO - PID 12211 | Number of tests: 6 10:16:44 INFO - PID 12211 | 10:16:44 INFO - PID 12211 | [#0] dojo.html Cycles:165 Average:6069.90 Median:6302.12 stddev:710.77 (11.3%) stddev-sans-first:673.08 10:16:44 INFO - PID 12211 | Values: 3078.0 3080.9 3121.2 3140.4 3166.7 6052.4 6076.1 6140.0 6151.0 6179.9 6144.5 6148.3 6171.1 6172.1 6180.2 5625.2 5667.8 5673.7 5686.3 5699.8 5662.6 5667.1 5668.8 5672.7 5675.8 5692.1 5695.7 5698.9 5704.2 5707.3 5687.4 5687.6 5697.0 5698.3 5713.2 5572.0 5603.6 5620.9 5655.7 5714.5 6081.3 6089.6 6114.9 6133.4 6179.4 6439.3 6477.5 6507.7 6534.0 6563.5 6500.2 6575.7 6586.3 6594.6 6596.6 6191.4 6192.3 6204.2 6234.6 6239.7 6790.2 6791.8 6859.3 6869.9 6884.6 6253.5 6486.8 6537.3 6555.3 6566.6 6391.9 6539.7 6550.8 6653.6 6660.8 6495.3 6498.7 6526.9 6617.8 6632.1 6483.1 6537.8 6544.5 6599.1 6630.7 5222.5 5282.7 5343.4 5356.2 5364.1 4535.6 4546.5 4548.7 4549.0 4581.5 5739.0 5750.5 5754.5 5766.1 5766.4 6624.5 6627.9 6633.2 6645.4 6660.2 5655.8 5700.1 5770.8 5777.2 5792.0 5847.3 5848.4 5852.2 5854.0 5861.6 6470.8 6482.8 6483.1 6485.5 6492.2 6576.4 6680.4 6705.7 6713.5 6718.4 6377.9 6385.6 6403.0 6459.1 6485.7 6328.0 6422.5 6449.0 6468.1 6538.7 5503.8 6489.1 6550.1 6654.7 6672.4 6365.2 6382.9 6462.2 6472.0 6504.9 6366.9 6413.8 6481.3 6490.3 6506.1 6257.4 6318.5 6356.4 6360.5 6362.2 6388.7 6436.7 6443.2 6444.9 6460.9 6165.8 6201.4 6239.9 6250.1 6302.1 10:16:44 INFO - PID 12211 | 10:16:44 INFO - PID 12211 | [#1] ext.html Cycles:180 Average:38083.72 Median:34654.07 stddev:54971.61 (158.6%) stddev-sans-first:55054.26 10:16:44 INFO - PID 12211 | Values: 729.4 780.2 804.4 806.2 1174.7 30386.4 31012.5 39736.8 40785.6 40841.2 41483.2 41913.8 41974.6 41992.0 42013.2 7619.4 7730.0 7733.9 7767.0 7767.6 7182.9 7603.2 7703.2 7705.8 7717.9 7317.5 7724.4 7727.5 7737.1 7749.2 7682.8 7750.0 7754.5 7757.1 7774.9 7682.7 7688.5 7688.5 7690.7 7745.7 38567.8 38702.0 38869.0 38920.4 38967.4 3552.5 3583.0 3585.9 3591.2 3594.4 30716.8 30737.7 30750.5 30847.5 30924.7 4656.8 4786.9 4897.6 4916.8 4936.5 202148.0 202574.0 202650.0 202683.0 203286.0 48636.5 48837.0 48859.0 48929.8 48953.0 50224.5 50496.0 50676.7 50738.2 50759.0 15835.5 16342.8 16369.5 16405.4 16422.9 13378.6 13395.1 13406.3 13410.3 13413.4 12718.2 14501.4 14517.8 14635.2 14661.0 5216.2 5404.2 5405.1 5412.6 5510.6 973.3 988.3 1000.5 1020.4 1021.9 22789.4 22848.6 23002.1 23246.8 23831.4 1078.2 1101.3 1110.5 1115.6 1132.2 11806.0 12207.5 12212.9 12242.4 12465.7 226272.0 231370.0 231386.0 231712.0 232482.0 54741.2 55002.4 55301.7 55309.2 55331.4 1467.1 1491.7 1506.7 1508.3 1511.0 26196.3 27022.2 27245.7 27346.7 27521.3 38097.2 38250.0 38367.4 38367.8 38428.0 201622.0 201658.0 201837.0 202519.0 202671.0 33684.0 34554.7 34753.5 34767.3 34878.7 40042.6 41830.6 41851.8 41975.6 42561.4 29720.6 30513.4 30622.2 30692.4 30768.5 30049.5 31492.2 31517.2 31530.2 31947.0 34210.5 34502.3 34504.8 38128.8 38856.6 32009.8 32487.0 33051.5 33505.8 33619.1 24166.8 24473.9 24933.0 25381.4 25392.8 10:16:44 INFO - PID 12211 | 10:16:44 INFO - PID 12211 | [#2] jquery.html Cycles:180 Average:35881.51 Median:22789.87 stddev:85416.13 (374.8%) stddev-sans-first:85614.56 10:16:44 INFO - PID 12211 | Values: 552.8 563.5 563.8 564.2 579.7 21369.6 22250.2 22419.9 22422.6 22496.3 25595.9 26204.3 26286.3 26359.5 26367.3 6327.8 6427.9 6469.3 6503.3 6508.5 6120.6 6328.5 6386.2 6408.1 6420.3 6164.5 6315.8 6365.3 6403.2 6435.4 6382.0 6386.0 6391.4 6391.7 6415.0 5783.6 5823.8 5828.4 5836.4 5906.1 26885.0 27215.6 27793.4 28172.6 28205.0 1900.4 1910.2 1928.5 1944.6 1948.8 18048.4 18513.4 18732.3 18746.7 18770.1 2302.4 2335.0 2407.5 2413.6 2415.9 128798.0 132594.0 133033.0 133121.0 138486.3 13728.7 14178.9 14219.4 14678.0 15137.4 22781.3 22798.4 22805.9 22823.4 22931.0 10208.5 10370.2 10462.9 10517.8 10609.4 9832.2 9873.4 9877.1 9945.8 10105.8 7690.5 8884.0 8934.9 8998.3 9153.7 2623.2 2664.5 2724.6 2734.3 2750.1 945.3 950.9 958.5 984.7 1002.4 19487.0 19581.2 20168.8 23211.5 25853.9 1041.6 1053.7 1068.7 1071.7 1075.5 11221.9 11228.7 11343.3 11370.4 11604.3 492589.0 498080.0 513335.0 517183.0 519276.0 113382.0 122067.0 123567.4 124128.0 126526.4 1288.3 1290.8 1294.5 1331.1 1339.3 18620.9 21218.2 21280.7 21649.8 21768.8 56184.0 57831.7 58796.7 59004.1 60729.1 76959.2 76975.2 77198.6 77756.0 78089.0 18751.4 20486.3 20535.6 20624.4 20749.2 22882.1 22893.7 23207.8 23350.4 23374.4 18085.7 18282.3 18382.8 18507.7 18691.0 15557.5 15601.9 16706.7 16877.0 16921.6 18918.5 19167.0 19240.6 19281.7 19290.8 19141.7 20145.2 20210.5 20241.5 20313.9 17871.1 18210.8 18306.9 18428.8 18478.8 10:16:44 INFO - PID 12211 | 10:16:44 INFO - PID 12211 | [#3] mootools.html Cycles:180 Average:8463.34 Median:2579.66 stddev:5822.50 (225.7%) stddev-sans-first:5814.19 10:16:44 INFO - PID 12211 | Values: 1333.3 1385.5 1393.3 1396.0 1398.0 13197.2 13897.3 13926.9 13933.9 13980.5 13563.5 13954.2 14367.9 14434.1 14440.9 2534.3 2580.9 2587.9 2595.2 2604.8 2577.1 2611.9 2626.6 2629.0 2630.9 2503.4 2541.2 2579.6 2589.2 2592.5 2576.4 2658.7 2678.2 2684.2 2686.8 2579.7 2593.4 2593.7 2595.1 2601.2 13674.8 13805.2 13808.1 13837.0 13863.9 7318.8 7567.4 7603.6 7615.9 7649.6 4626.4 4831.3 4922.0 4929.3 4972.3 1808.5 1812.4 1817.5 1866.2 1873.3 22730.7 22973.2 22987.4 22994.3 23002.9 5992.4 6028.9 6132.9 6239.7 6289.6 14842.3 14843.6 14887.6 14893.5 14898.7 6800.7 6879.6 6888.0 6898.0 6910.7 6061.3 6257.2 6259.3 6313.3 6365.8 4695.0 4818.4 4875.7 4891.0 4905.1 1354.7 1356.1 1380.5 1380.7 1387.8 884.2 892.4 908.0 924.8 936.9 7215.1 7235.2 7274.0 7308.2 7366.7 679.8 683.4 686.4 691.7 692.7 4411.3 4725.7 4778.4 5073.7 5186.7 17812.4 18137.5 18989.5 18989.5 18992.5 18395.9 18415.8 18820.2 18946.2 19048.2 10681.0 10868.6 10903.7 10965.7 11001.0 7753.7 7947.1 8051.2 8052.7 8163.4 15988.6 16344.9 16394.1 16734.4 16802.4 15674.4 16128.7 17044.7 17273.3 17466.0 10055.4 10577.2 10663.3 10668.9 10841.9 12778.7 12910.3 12980.1 12981.2 13049.3 10301.8 10618.5 10732.1 10732.7 10948.4 10045.4 10362.1 10435.5 10455.1 10512.8 9176.5 9300.4 9402.0 9642.5 9706.8 6382.6 6416.3 6664.6 6742.7 6860.2 7167.9 7342.6 7403.6 7462.9 7481.8 10:16:44 INFO - PID 12211 | 10:16:44 INFO - PID 12211 | [#4] prototype.html Cycles:180 Average:5327.45 Median:5878.82 stddev:1502.74 (25.6%) stddev-sans-first:1461.96 10:16:44 INFO - PID 12211 | Values: 464.7 473.8 502.6 505.7 507.9 5756.4 5899.4 5947.0 5972.9 6034.2 5767.9 5822.0 5925.5 5938.5 5994.0 4780.6 4783.6 4810.7 4858.3 4865.4 4797.8 4856.8 4871.0 4877.8 4878.0 4756.9 4762.6 4805.2 4811.0 4844.2 4548.1 4700.3 4781.2 4793.4 4851.5 4322.7 4327.4 4334.5 4349.4 4363.1 5788.8 5864.4 5893.3 5902.3 5912.1 5496.6 5509.5 5527.1 5544.8 5555.3 6315.7 6323.0 6325.7 6399.2 6404.6 4732.4 4738.7 4738.8 4743.4 4748.5 6663.3 6681.6 6729.1 6756.0 6771.1 4845.2 4846.8 4855.8 4856.6 4875.3 4823.5 4869.9 4889.9 4896.0 4930.5 5408.7 5435.5 5435.6 5489.6 5499.1 6384.6 6403.6 6416.9 6428.0 6433.2 1677.3 1684.7 1685.3 1686.6 1694.3 1078.2 1160.1 1173.0 1190.7 1191.5 5421.0 5513.1 5570.2 5602.9 5680.1 6358.5 6469.8 6526.9 6552.7 6564.9 5605.6 5680.7 5715.7 5753.3 5763.1 3410.3 3425.7 3436.5 3454.0 3461.6 6237.9 6252.0 6296.3 6346.3 6370.5 6653.8 6669.2 6694.8 6721.6 6771.3 6255.4 6298.4 6306.8 6337.7 6338.6 6411.2 6510.3 6552.1 6562.0 6567.7 6379.2 6396.2 6421.7 6524.0 6543.2 6244.7 6254.3 6287.5 6287.9 6337.3 4711.5 4724.8 4744.0 4749.5 4753.1 6513.6 6514.8 6516.7 6523.7 6547.2 6275.7 6284.4 6336.1 6348.4 6363.2 6260.0 6354.1 6365.7 6369.5 6397.5 6229.8 6260.4 6261.2 6310.2 6318.5 6221.3 6236.4 6267.9 6324.6 6340.5 5633.8 6049.7 6101.6 6153.8 6226.7 10:16:44 INFO - PID 12211 | 10:16:44 INFO - PID 12211 | [#5] yui.html Cycles:180 Average:22742.89 Median:19153.69 stddev:31395.39 (163.9%) stddev-sans-first:31440.62 10:16:44 INFO - PID 12211 | Values: 898.5 920.0 921.9 924.6 929.8 16670.6 18213.3 18760.0 18826.9 18941.9 15941.6 16177.2 18658.9 19586.1 20846.2 1000.9 1023.1 1025.5 1026.1 1032.9 1023.8 1027.5 1029.1 1038.4 1041.5 772.6 1047.9 1093.0 1093.4 1093.8 1072.2 1073.6 1079.4 1081.8 1083.0 793.8 794.0 794.4 795.0 795.7 18489.4 18564.7 18890.9 18963.1 19535.1 17241.7 17263.7 17610.6 17662.5 17739.5 15920.2 15950.2 15969.4 16052.4 16076.6 3387.1 3392.7 3450.5 3454.4 3462.2 128910.0 129718.0 134783.0 135907.0 136324.0 15568.3 15634.9 15923.1 15983.6 16196.5 30098.4 31405.0 31453.4 31535.0 31584.4 8486.0 8656.1 8733.7 8794.4 8853.2 4201.6 4246.8 4272.9 4298.5 4304.1 4843.8 5012.4 5060.2 5078.3 5085.5 891.9 929.1 933.1 1046.1 1070.6 626.8 688.2 694.8 703.1 708.1 11215.7 11298.7 11707.3 11939.6 12336.8 664.7 685.6 687.6 696.6 700.9 5120.8 5273.7 5277.4 5336.3 5490.7 97641.0 100115.0 102826.5 106788.0 107643.0 94192.0 97334.0 98227.5 98824.5 98946.5 76896.6 77722.2 80084.0 81521.8 83962.0 10261.7 10371.4 10474.8 10705.6 10853.2 33201.2 33339.5 35250.8 35906.6 36469.0 44995.0 46131.0 46534.3 64891.7 65155.7 17562.7 18509.4 18866.6 19086.7 19403.9 19993.4 20488.4 21453.2 21761.9 21783.5 15396.8 17119.0 17725.8 19241.4 19643.7 17695.2 17903.1 19151.1 19156.2 19737.4 18453.0 18679.8 19105.5 19115.9 19450.1 12895.6 13734.5 14287.4 14443.1 14516.5 13926.0 14278.6 14324.1 14364.6 14496.9 10:16:44 INFO - PID 12211 | -------- Summary: end -------- 10:16:44 INFO - PID 12211 | 10:16:44 INFO - TEST-INFO | 12211: exit 0 10:16:44 INFO - TEST-OK | dromaeo_css | took 1110906ms 10:16:44 INFO - TEST-START | kraken 10:16:44 INFO - Initialising browser for kraken test... 10:16:44 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40530/getInfo.html -profile /tmp/tmp1DB3xC/profile 10:16:44 INFO - TEST-INFO | started process 12624 (/builds/slave/test/build/application/firefox/firefox http://localhost:40530/getInfo.html) 10:16:50 INFO - TEST-INFO | 12624: exit 0 10:16:50 INFO - Browser initialized. 10:16:50 INFO - Running cycle 1/1 for kraken test... 10:16:50 INFO - TEST-INFO | started process 12894 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1DB3xC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 10:17:02 INFO - PID 12894 | RSS: Main: 175636480 10:17:02 INFO - PID 12894 | 10:17:04 INFO - PID 12894 | Cycle 1(1): loaded http://localhost:40530/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40530/tests/kraken/driver.html?testName=audio-beat-detection) 10:17:05 INFO - PID 12894 | RSS: Main: 180183040 10:17:05 INFO - PID 12894 | 10:17:08 INFO - PID 12894 | Cycle 1(1): loaded http://localhost:40530/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40530/tests/kraken/driver.html?testName=audio-dft) 10:17:08 INFO - PID 12894 | RSS: Main: 181190656 10:17:08 INFO - PID 12894 | 10:17:11 INFO - PID 12894 | Cycle 1(1): loaded http://localhost:40530/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40530/tests/kraken/driver.html?testName=audio-fft) 10:17:12 INFO - PID 12894 | RSS: Main: 174661632 10:17:12 INFO - PID 12894 | 10:17:14 INFO - PID 12894 | Cycle 1(1): loaded http://localhost:40530/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40530/tests/kraken/driver.html?testName=audio-oscillator) 10:17:14 INFO - PID 12894 | RSS: Main: 175644672 10:17:14 INFO - PID 12894 | 10:17:17 INFO - PID 12894 | Cycle 1(1): loaded http://localhost:40530/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40530/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:17:17 INFO - PID 12894 | RSS: Main: 175759360 10:17:17 INFO - PID 12894 | 10:17:20 INFO - PID 12894 | Cycle 1(1): loaded http://localhost:40530/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40530/tests/kraken/driver.html?testName=imaging-darkroom) 10:17:21 INFO - PID 12894 | RSS: Main: 179191808 10:17:21 INFO - PID 12894 | 10:17:24 INFO - PID 12894 | Cycle 1(1): loaded http://localhost:40530/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40530/tests/kraken/driver.html?testName=imaging-desaturate) 10:17:25 INFO - PID 12894 | RSS: Main: 180068352 10:17:25 INFO - PID 12894 | 10:17:28 INFO - PID 12894 | Cycle 1(1): loaded http://localhost:40530/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40530/tests/kraken/driver.html?testName=json-parse-financial) 10:17:28 INFO - PID 12894 | RSS: Main: 178614272 10:17:28 INFO - PID 12894 | 10:17:30 INFO - PID 12894 | Cycle 1(1): loaded http://localhost:40530/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40530/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:17:31 INFO - PID 12894 | RSS: Main: 178688000 10:17:31 INFO - PID 12894 | 10:17:32 INFO - PID 12894 | Cycle 1(1): loaded http://localhost:40530/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40530/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:17:33 INFO - PID 12894 | RSS: Main: 178606080 10:17:33 INFO - PID 12894 | 10:17:35 INFO - PID 12894 | Cycle 1(1): loaded http://localhost:40530/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40530/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:17:36 INFO - PID 12894 | RSS: Main: 178950144 10:17:36 INFO - PID 12894 | 10:17:38 INFO - PID 12894 | Cycle 1(1): loaded http://localhost:40530/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40530/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:17:39 INFO - PID 12894 | RSS: Main: 179257344 10:17:39 INFO - PID 12894 | 10:17:42 INFO - PID 12894 | Cycle 1(1): loaded http://localhost:40530/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40530/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:17:43 INFO - PID 12894 | RSS: Main: 178802688 10:17:43 INFO - PID 12894 | 10:17:44 INFO - PID 12894 | Cycle 1(1): loaded http://localhost:40530/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40530/tests/kraken/driver.html?testName=ai-astar) 10:17:45 INFO - PID 12894 | RSS: Main: 180772864 10:17:45 INFO - PID 12894 | 10:17:45 INFO - PID 12894 | __start_tp_report 10:17:45 INFO - PID 12894 | _x_x_mozilla_page_load 10:17:45 INFO - PID 12894 | _x_x_mozilla_page_load_details 10:17:45 INFO - PID 12894 | |i|pagename|runs| 10:17:45 INFO - PID 12894 | |0;ai-astar;98;109;101;110;108;109;113;102;108;110 10:17:45 INFO - PID 12894 | |1;audio-beat-detection;147;119;153;175;125;151;143;118;168;119 10:17:45 INFO - PID 12894 | |2;audio-dft;163;163;163;163;164;169;162;168;166;163 10:17:45 INFO - PID 12894 | |3;audio-fft;74;76;75;168;75;77;76;76;99;77 10:17:45 INFO - PID 12894 | |4;audio-oscillator;82;83;140;81;82;81;135;81;82;132 10:17:45 INFO - PID 12894 | |5;imaging-gaussian-blur;114;113;114;115;113;113;113;113;113;113 10:17:45 INFO - PID 12894 | |6;imaging-darkroom;150;151;150;150;150;152;150;150;149;151 10:17:45 INFO - PID 12894 | |7;imaging-desaturate;95;94;93;94;94;94;93;93;94;94 10:17:45 INFO - PID 12894 | |8;json-parse-financial;58;56;55;58;57;58;59;58;56;60 10:17:45 INFO - PID 12894 | |9;json-stringify-tinderbox;54;54;55;54;55;55;54;55;54;54 10:17:45 INFO - PID 12894 | |10;stanford-crypto-aes;78;80;77;77;89;78;76;77;77;81 10:17:45 INFO - PID 12894 | |11;stanford-crypto-ccm;133;131;126;126;123;141;129;121;136;170 10:17:45 INFO - PID 12894 | |12;stanford-crypto-pbkdf2;184;183;179;183;178;182;181;180;177;180 10:17:45 INFO - PID 12894 | |13;stanford-crypto-sha256-iterative;69;68;74;71;68;69;69;69;67;67 10:17:45 INFO - PID 12894 | __end_tp_report 10:17:45 INFO - PID 12894 | __start_cc_report 10:17:45 INFO - PID 12894 | _x_x_mozilla_cycle_collect,993 10:17:45 INFO - PID 12894 | __end_cc_report 10:17:45 INFO - PID 12894 | __startTimestamp1500398265090__endTimestamp 10:17:45 INFO - PID 12894 | 10:17:45 INFO - PID 12894 | ------- Summary: start ------- 10:17:45 INFO - PID 12894 | Number of tests: 14 10:17:45 INFO - PID 12894 | 10:17:45 INFO - PID 12894 | [#0] ai-astar Cycles:10 Average:106.80 Median:109.50 stddev:4.78 (4.4%) stddev-sans-first:3.87 10:17:45 INFO - PID 12894 | Values: 98.0 109.0 101.0 110.0 108.0 109.0 113.0 102.0 108.0 110.0 10:17:45 INFO - PID 12894 | 10:17:45 INFO - PID 12894 | [#1] audio-beat-detection Cycles:10 Average:141.80 Median:149.00 stddev:20.86 (14.0%) stddev-sans-first:22.04 10:17:45 INFO - PID 12894 | Values: 147.0 119.0 153.0 175.0 125.0 151.0 143.0 118.0 168.0 119.0 10:17:45 INFO - PID 12894 | 10:17:45 INFO - PID 12894 | [#2] audio-dft Cycles:10 Average:164.40 Median:163.50 stddev:2.41 (1.5%) stddev-sans-first:2.51 10:17:45 INFO - PID 12894 | Values: 163.0 163.0 163.0 163.0 164.0 169.0 162.0 168.0 166.0 163.0 10:17:45 INFO - PID 12894 | 10:17:45 INFO - PID 12894 | [#3] audio-fft Cycles:10 Average:87.30 Median:76.00 stddev:29.30 (38.5%) stddev-sans-first:30.67 10:17:45 INFO - PID 12894 | Values: 74.0 76.0 75.0 168.0 75.0 77.0 76.0 76.0 99.0 77.0 10:17:45 INFO - PID 12894 | 10:17:45 INFO - PID 12894 | [#4] audio-oscillator Cycles:10 Average:97.90 Median:81.50 stddev:26.14 (32.1%) stddev-sans-first:27.08 10:17:45 INFO - PID 12894 | Values: 82.0 83.0 140.0 81.0 82.0 81.0 135.0 81.0 82.0 132.0 10:17:45 INFO - PID 12894 | 10:17:45 INFO - PID 12894 | [#5] imaging-gaussian-blur Cycles:10 Average:113.40 Median:113.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 10:17:45 INFO - PID 12894 | Values: 114.0 113.0 114.0 115.0 113.0 113.0 113.0 113.0 113.0 113.0 10:17:45 INFO - PID 12894 | 10:17:45 INFO - PID 12894 | [#6] imaging-darkroom Cycles:10 Average:150.30 Median:150.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 10:17:45 INFO - PID 12894 | Values: 150.0 151.0 150.0 150.0 150.0 152.0 150.0 150.0 149.0 151.0 10:17:45 INFO - PID 12894 | 10:17:45 INFO - PID 12894 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 10:17:45 INFO - PID 12894 | Values: 95.0 94.0 93.0 94.0 94.0 94.0 93.0 93.0 94.0 94.0 10:17:45 INFO - PID 12894 | 10:17:45 INFO - PID 12894 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:58.00 stddev:1.51 (2.6%) stddev-sans-first:1.59 10:17:45 INFO - PID 12894 | Values: 58.0 56.0 55.0 58.0 57.0 58.0 59.0 58.0 56.0 60.0 10:17:45 INFO - PID 12894 | 10:17:45 INFO - PID 12894 | [#9] json-stringify-tinderbox Cycles:10 Average:54.40 Median:54.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 10:17:45 INFO - PID 12894 | Values: 54.0 54.0 55.0 54.0 55.0 55.0 54.0 55.0 54.0 54.0 10:17:45 INFO - PID 12894 | 10:17:45 INFO - PID 12894 | [#10] stanford-crypto-aes Cycles:10 Average:79.00 Median:78.00 stddev:3.83 (4.9%) stddev-sans-first:4.04 10:17:45 INFO - PID 12894 | Values: 78.0 80.0 77.0 77.0 89.0 78.0 76.0 77.0 77.0 81.0 10:17:45 INFO - PID 12894 | 10:17:45 INFO - PID 12894 | [#11] stanford-crypto-ccm Cycles:10 Average:133.60 Median:132.00 stddev:14.14 (10.7%) stddev-sans-first:15.00 10:17:45 INFO - PID 12894 | Values: 133.0 131.0 126.0 126.0 123.0 141.0 129.0 121.0 136.0 170.0 10:17:45 INFO - PID 12894 | 10:17:45 INFO - PID 12894 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.70 Median:181.50 stddev:2.31 (1.3%) stddev-sans-first:2.12 10:17:45 INFO - PID 12894 | Values: 184.0 183.0 179.0 183.0 178.0 182.0 181.0 180.0 177.0 180.0 10:17:45 INFO - PID 12894 | 10:17:45 INFO - PID 12894 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.00 stddev:2.08 (3.0%) stddev-sans-first:2.20 10:17:45 INFO - PID 12894 | Values: 69.0 68.0 74.0 71.0 68.0 69.0 69.0 69.0 67.0 67.0 10:17:45 INFO - PID 12894 | -------- Summary: end -------- 10:17:45 INFO - PID 12894 | 10:17:45 INFO - PID 12894 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 10:17:45 INFO - TEST-INFO | 12894: exit 0 10:17:45 INFO - TEST-OK | kraken | took 60971ms 10:17:45 INFO - SUITE-END | took 1171s 10:17:46 INFO - Completed test suite (00:19:32) 10:17:46 INFO - javascript benchmark 10:17:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8345.217082967123, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3078.0458058002023, 3080.907572772815, 3121.2040929562963, 3140.4190224808412, 3166.7466602671775, 6052.42501962314, 6076.149339092525, 6140.048292514667, 6150.969245153774, 6179.949408600537, 6144.477719393845, 6148.262208534982, 6171.074338849164, 6172.074188871671, 6180.227471566054, 5625.1843482694985, 5667.773289068443, 5673.716314184295, 5686.317641882959, 5699.829005129832, 5662.631928924607, 5667.064934285859, 5668.773249070032, 5672.68800215988, 5675.801346952858, 5692.0608099663505, 5695.743691533891, 5698.943010569903, 5704.172894930232, 5707.258056452676, 5687.374388817227, 5687.601867869251, 5697.0315046442, 5698.31620205578, 5713.171590119409, 5571.9721401392735, 5603.579731520112, 5620.9437905621235, 5655.660660360391, 5714.51426628737, 6081.270247570275, 6089.634621922654, 6114.938850611481, 6133.386661333876, 6179.412955769195, 6439.291677915426, 6477.514186436036, 6507.743170349757, 6534.0, 6563.474922006228, 6500.187476565429, 6575.671216439207, 6586.27550969393, 6594.604323740543, 6596.571222870546, 6191.380861913818, 6192.256929168529, 6204.224471941007, 6234.625922444668, 6239.688015599248, 6790.1851777786, 6791.796246112624, 6859.279775623588, 6869.931300686929, 6884.552504087218, 6253.53098517613, 6486.772962946275, 6537.313582073909, 6555.311692272243, 6566.573172743756, 6391.872162556723, 6539.673016349164, 6550.836229094215, 6653.600783952978, 6660.766873159513, 6495.3179916109075, 6498.740050397932, 6526.902096468557, 6617.7683781067435, 6632.137822083098, 6483.092367068614, 6537.836554086186, 6544.476441884638, 6599.142111525471, 6630.734770609218, 5222.451642577475, 5282.709450980233, 5343.3587969443915, 5356.196570514376, 5364.061289274361, 4535.614472769851, 4546.4544254689645, 4548.7043342183315, 4548.977255113769, 4581.541845815392, 5739.0, 5750.482456578928, 5754.539636829044, 5766.135079737989, 5766.365699773021, 6624.503162262849, 6627.900581491282, 6633.170853643294, 6645.401913827779, 6660.234073081658, 5655.773769049192, 5700.116481945305, 5770.769169233267, 5777.19119323295, 5791.9710401448565, 5847.298324201123, 5848.385919478478, 5852.180694702745, 5853.970730146322, 5861.589688721834, 6470.805875823733, 6482.773102941469, 6483.124778154983, 6485.4811615070685, 6492.220933487917, 6576.408123268929, 6680.365365290289, 6705.731770729311, 6713.496487763535, 6718.428933540704, 6377.8724425512155, 6385.616862988236, 6403.007533832263, 6459.128017717736, 6485.708143133665, 6328.0, 6422.486201103994, 6449.0, 6468.062130991074, 6538.738450461929, 5503.752331145028, 6489.091527186103, 6550.082988381535, 6654.667266636746, 6672.399484046459, 6365.172527571386, 6382.904256436064, 6462.224533055875, 6471.964485682268, 6504.869902601827, 6366.9044964326495, 6413.839654008687, 6481.287058423664, 6490.3185165558825, 6506.056621789719, 6257.374262573706, 6318.462930650762, 6356.396142366468, 6360.459360954371, 6362.236531616236, 6388.680565971776, 6436.710348034256, 6443.162388889415, 6444.9355506444335, 6460.903086453801, 6165.753369865155, 6201.441870231701, 6239.937600623935, 6250.062490626442, 6302.117703521419], "value": 6017.148444896409, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.4346881167095, 780.2275747010459, 804.4127786715699, 806.2219957740779, 1174.6828356343783, 30386.392272154542, 31012.53481197781, 39736.80131599341, 40785.59214407855, 40841.18317633645, 41483.17033659325, 41913.790431047804, 41974.58025419745, 41992.0, 42013.159736805246, 7619.390448764113, 7730.0, 7733.9226607734045, 7766.961165194166, 7767.572783496906, 7182.928170718278, 7603.239676032408, 7703.191164927686, 7705.845883082335, 7717.922820771804, 7317.524360916524, 7724.42066844986, 7727.459077864552, 7737.148913619498, 7749.225077492262, 7682.807929801744, 7749.961250193769, 7754.495957762784, 7757.146713861471, 7774.883376749353, 7682.7311044113185, 7688.500247483896, 7688.5386876787015, 7690.653920573532, 7745.728899488491, 38567.80716096402, 38702.0, 38869.0, 38920.41619375712, 38967.41548876769, 3552.5381700379044, 3582.960941327012, 3585.94621080684, 3591.2278860044953, 3594.4428613564937, 30716.846415768003, 30737.692623073708, 30750.53874191889, 30847.53728694049, 30924.690753092407, 4656.837010704609, 4786.922942337981, 4897.632677549198, 4916.827911023097, 4936.531029552187, 202148.0, 202574.0, 202650.0, 202683.0, 203286.0, 48636.5136348632, 48837.0, 48859.0, 48929.755351223015, 48953.0, 50224.497755021985, 50496.0, 50676.74661626668, 50738.23892641613, 50759.0, 15835.524934251991, 16342.75485867713, 16369.508914732578, 16405.42581009659, 16422.917885410498, 13378.598642040753, 13395.12931659439, 13406.329683515785, 13410.262435566137, 13413.39639716215, 12718.173318734252, 14501.419943202365, 14517.78223326651, 14635.195064271355, 14661.0, 5216.21756736485, 5404.189371594213, 5405.054115529767, 5412.594055445778, 5510.559155267649, 973.2943615878431, 988.3427520698799, 1000.46475135802, 1020.4081632653043, 1021.918246540275, 22789.430264243194, 22848.623672050617, 23002.079916803475, 23246.76753232446, 23831.404214894766, 1078.2398409121552, 1101.2676570080966, 1110.4891749795024, 1115.587232723881, 1132.1961407400838, 11806.0, 12207.450664720109, 12212.877871221352, 12242.44908979098, 12465.688357790947, 226272.0, 231370.0, 231386.0, 231712.0, 232482.0, 54741.178882316795, 55002.44997549973, 55301.72349138228, 55309.170362444595, 55331.44668553343, 1467.149053548939, 1491.7016596680708, 1506.6760646461064, 1508.2609521334466, 1510.9546713598609, 26196.345091372867, 27022.189334320003, 27245.72754272511, 27346.726532735214, 27521.31196720098, 38097.23805523819, 38250.0, 38367.42448863213, 38367.808160959015, 38428.0, 201622.0, 201658.0, 201837.0, 202519.0, 202671.0, 33684.0, 34554.65445345514, 34753.47869782006, 34767.30465390729, 34878.65121348754, 40042.599574003885, 41830.581694183886, 41851.7907410461, 41975.58024419839, 42561.361579575714, 29720.554191686708, 30513.38973220479, 30622.234444139074, 30692.386152276384, 30768.538471923388, 30049.549256761602, 31492.212694682818, 31517.212069697525, 31530.211744706565, 31947.0, 34210.48684269688, 34502.30995380128, 34504.82747586246, 38128.809355953046, 38856.6114338853, 32009.8399508001, 32487.0, 33051.504227436126, 33505.83247083749, 33619.12522684246, 24166.758332417154, 24473.877630611732, 24933.0, 25381.365465863502, 25392.849122981963], "value": 17210.842122598562, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.825859854146, 563.5041163775877, 563.8421242052227, 564.2016546586581, 579.6985567504897, 21369.572608547838, 22250.221242256524, 22419.887900560476, 22422.55154896903, 22496.32511024668, 25595.872020639916, 26204.344891377725, 26286.342841428974, 26359.47281054378, 26367.3408164796, 6327.810165695025, 6427.93572064278, 6469.288378278386, 6503.349665033506, 6508.47932165428, 6120.571559990803, 6328.525360597951, 6386.2336519617475, 6408.070829729686, 6420.325865784095, 6164.506839452833, 6315.810525684214, 6365.267994180682, 6403.231612206541, 6435.388638079375, 6381.968090159519, 6386.0, 6391.424771770539, 6391.6804159792055, 6415.0377443383395, 5783.566232532577, 5823.79616713413, 5828.358880523181, 5836.416358364172, 5906.084556893688, 26885.0, 27215.591766123525, 27793.444131117263, 28172.577411338847, 28205.0, 1900.3633782682818, 1910.1977169588667, 1928.469670840516, 1944.562473443478, 1948.7953764854628, 18048.368307109187, 18513.352032678926, 18732.250709971584, 18746.718799218022, 18770.061496925235, 2302.3553405046614, 2335.0309621507054, 2407.5305315463493, 2413.6017557103055, 2415.9396015099587, 128798.0, 132594.0, 133033.0, 133121.0, 138486.30756846152, 13728.725425491435, 14178.85821141796, 14219.36012879423, 14678.0, 15137.394504219928, 22781.31656050321, 22798.43003924882, 22805.885970570373, 22823.429414264443, 22931.0, 10208.540615672313, 10370.170386369073, 10462.947685261524, 10517.789644207072, 10609.363438193734, 9832.164266037467, 9873.358231714916, 9877.11106000449, 9945.80108397828, 10105.848412273821, 7690.500117492345, 8884.0, 8934.86597701035, 8998.280137588978, 9153.725388238363, 2623.1737002844043, 2664.454616322528, 2724.6185534024858, 2734.2890848379566, 2750.119961612265, 945.2721404518483, 950.8573713942826, 958.5015791788231, 984.6701355045997, 1002.3735165521549, 19487.0, 19581.216751329786, 20168.798312016985, 23211.53576928452, 25853.870730646602, 1041.6093964763213, 1053.7154968158707, 1068.6793961811425, 1071.6945670483874, 1075.5321435175724, 11221.94389028066, 11228.719282017852, 11343.319400835977, 11370.431478426046, 11604.329973870847, 492589.0, 498080.0, 513335.0, 517183.0, 519276.0, 113382.0, 122067.0, 123567.38216308861, 124128.0, 126526.36736816257, 1288.3365066990507, 1290.7741145299535, 1294.5274974634378, 1331.1281110872394, 1339.270097796703, 18620.906895465436, 21218.151273949486, 21280.680789788476, 21649.783502164777, 21768.782312176674, 56184.0, 57831.71084144552, 58796.706016469645, 59004.1149382751, 60729.089063664964, 76959.23040769521, 76975.2302476968, 77198.61400692961, 77756.0, 78089.0, 18751.437456876316, 20486.282980095624, 20535.589288213854, 20624.381268561967, 20749.1700331985, 22882.08471661115, 22893.65659515142, 23207.767922321236, 23350.416239593465, 23374.415639609146, 18085.728714069563, 18282.268709251483, 18382.816171838644, 18507.722384164517, 18691.0, 15557.45548905783, 15601.921990389976, 16706.665866682357, 16877.0, 16921.576960575592, 18918.527036823638, 19167.0, 19240.615187696447, 19281.710774338117, 19290.807091929462, 19141.712874307173, 20145.19419223273, 20210.494737631678, 20241.493962651053, 20313.898430507754, 17871.106444677975, 18210.817891821443, 18306.90846545759, 18428.81571184271, 18478.81521184771], "value": 12258.58110346217, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.346660136533, 1385.5150697255963, 1393.3033483258364, 1396.0437100586093, 1397.9860201397987, 13197.208167509956, 13897.305134743261, 13926.930365348158, 13933.860661393395, 13980.510682126127, 13563.45746170152, 13954.232517211549, 14367.92816035921, 14434.06178598393, 14440.855591444082, 2534.3410713214603, 2580.870956452179, 2587.922362329133, 2595.221433569931, 2604.7525485078886, 2577.059373328731, 2611.9085831995885, 2626.579747240442, 2628.986855065722, 2630.8947642094295, 2503.4492411669494, 2541.224926397448, 2579.638850560923, 2589.223233030093, 2592.5333439980795, 2576.4331846993723, 2658.734126587345, 2678.2099280712237, 2684.2349930269975, 2686.7850571954295, 2579.6904371475543, 2593.3775893785546, 2593.6757905261843, 2595.1046888823325, 2601.2456387647558, 13674.794878076837, 13805.17168969865, 13808.1024733392, 13837.0, 13863.930680346635, 7318.81702957425, 7567.432442566829, 7603.619819009083, 7615.923840761632, 7649.579273140031, 4626.444826620827, 4831.299461578052, 4922.0, 4929.260610908356, 4972.279019542182, 1808.5478630342413, 1812.4290848382718, 1817.5365281853176, 1866.2161892005254, 1873.2694249242807, 22730.659040114413, 22973.195938142086, 22987.42531436711, 22994.310170694906, 23002.884985574965, 5992.400759923973, 6028.9397106029255, 6132.908006379907, 6239.719212635442, 6289.55973081889, 14842.257887105601, 14843.554693359001, 14887.553373398816, 14893.47872824446, 14898.702025959421, 6800.6939687714175, 6879.552829066095, 6887.965560172267, 6897.965510172417, 6910.723571057101, 6061.302950160788, 6257.155284036597, 6259.3427690091885, 6313.337099604567, 6365.822322870284, 4694.976525117421, 4818.397700287464, 4875.658703890765, 4890.975545122252, 4905.092557876733, 1354.688421663023, 1356.1456282541935, 1380.5444203412849, 1380.6824430381068, 1387.8126452928927, 884.186548375496, 892.3797960417577, 907.9500627465553, 924.8196601662746, 936.9437833729997, 7215.098112735908, 7235.240299768554, 7274.0, 7308.196098429168, 7366.668499917517, 679.7858674517511, 683.4225079807654, 686.4234043403466, 691.7060249393987, 692.6675195905892, 4411.2721400968485, 4725.740084295328, 4778.402699662543, 5073.695578265316, 5186.714730689849, 17812.376566820098, 18137.455876323733, 18989.525261868286, 18989.525261868286, 18992.52518687044, 18395.90802045981, 18415.81584184141, 18820.24719011224, 18946.24215031411, 19048.238070477026, 10681.0, 10868.619598314179, 10903.672889813319, 10965.725856853642, 11001.0, 7753.65108570127, 7947.125816160102, 8051.2351326623875, 8052.677892884453, 8163.387745918961, 15988.600284992968, 16344.918275408547, 16394.098324591785, 16734.4142954996, 16802.411915582896, 15674.373025078869, 16128.67742645164, 17044.659106817548, 17273.309067637154, 17466.039367834892, 10055.39667619945, 10577.206709496972, 10663.253572250178, 10668.89331106679, 10841.915808419095, 12778.74442511126, 12910.289934053342, 12980.091393602357, 12981.156224845356, 13049.34753262352, 10301.845472317771, 10618.469076546296, 10732.14142868553, 10732.73168170802, 10948.39783811866, 10045.397276163454, 10362.067413932782, 10435.530401132119, 10455.059044685711, 10512.789744205225, 9176.495292758962, 9300.39547429442, 9401.952990235006, 9642.517874106126, 9706.805863882542, 6382.648954307558, 6416.3004394725585, 6664.566803157779, 6742.6628668567355, 6860.211075726355, 7167.856642867009, 7342.632868356454, 7403.555786652818, 7462.888056679263, 7481.7755467336065], "value": 6133.20387349996, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.697424262778, 473.7986355798787, 502.63307785316715, 505.6713136461301, 507.9136546787048, 5756.366799652045, 5899.410058994093, 5946.970265148673, 5972.88054238915, 6034.155218269435, 5767.884642307151, 5821.970890145554, 5925.4963328117055, 5938.52491800656, 5994.0, 4780.641451891105, 4783.569478746912, 4810.7113573185725, 4858.271259311114, 4865.391826021747, 4797.808087676506, 4856.761525810914, 4870.975645121769, 4877.804887804484, 4878.048780487807, 4756.9286460703115, 4762.571088744748, 4805.20714082176, 4810.97594512027, 4844.224924012159, 4548.090381923612, 4700.27145792401, 4781.187198176319, 4793.3768610080815, 4851.51484851512, 4322.724796185133, 4327.415798867144, 4334.479862416499, 4349.41282926804, 4363.105563359535, 5788.82633520995, 5864.354920958691, 5893.292804863401, 5902.32123305821, 5912.073523605843, 5496.615236933416, 5509.47659972302, 5527.115661494142, 5544.805931792368, 5555.2778138841695, 6315.715792789336, 6323.008252400525, 6325.683715814237, 6399.168108145911, 6404.615723056585, 4732.408448943882, 4738.691985020962, 4738.763061846894, 4743.383360163157, 4748.5251474852585, 6663.267040625527, 6681.599104053724, 6729.1252137221845, 6755.973208037569, 6771.119754431892, 4845.176320025604, 4846.80612775492, 4855.781489832966, 4856.587190088882, 4875.317455556225, 4823.541763532458, 4869.92861570461, 4889.902201955941, 4895.975520122377, 4930.506949305112, 5408.675479471244, 5435.510804027577, 5435.646682965595, 5489.560835133179, 5499.120140777536, 6384.553081284358, 6403.615783052938, 6416.871662566722, 6428.0, 6433.195850518685, 1677.2620047179203, 1684.7388654758531, 1685.3006002509067, 1686.5530634381898, 1694.2883988724766, 1078.2314616800056, 1160.0951258018758, 1172.9882701173049, 1190.660661711408, 1191.463841271414, 5420.972895135499, 5513.117901135799, 5570.192322113271, 5602.943970560324, 5680.14797780328, 6358.523110766711, 6469.7918175867935, 6526.878764401588, 6552.737890484327, 6564.932046405367, 5605.635633683797, 5680.687562184037, 5715.685637289906, 5753.33836608787, 5763.135529670583, 3410.334984677964, 3425.72594192469, 3436.4845273209216, 3454.0, 3461.5596004816543, 6237.906431403442, 6251.999040460604, 6296.307406185316, 6346.30190679025, 6370.490360771128, 6653.800385988428, 6669.166354205724, 6694.849193500061, 6721.5967041976655, 6771.28901465339, 6255.374462553708, 6298.433141017331, 6306.810795676137, 6337.746490140435, 6338.58799178052, 6411.166548348685, 6510.251321098134, 6552.115464412243, 6561.9500879859, 6567.671616419065, 6379.213820130371, 6396.200474940632, 6421.678916054272, 6524.021396790329, 6543.1821022372205, 6244.687765611792, 6254.2807577129215, 6287.465565426807, 6287.893555658906, 6337.302896681269, 4711.458182308941, 4724.787384567634, 4743.952560474351, 4749.548792864673, 4753.120672675566, 6513.609183449008, 6514.837129071811, 6516.73933042692, 6523.739050437929, 6547.214334279917, 6275.717592708431, 6284.366064823087, 6336.1446204763615, 6348.365163483615, 6363.204599425072, 6259.96870015647, 6354.122436746084, 6365.7135428904885, 6369.490440764635, 6397.488200943821, 6229.844253893508, 6260.40526150015, 6261.24865016201, 6310.211223597051, 6318.494520438262, 6221.3156552778255, 6236.438720514994, 6267.874642507216, 6324.588901721373, 6340.524460665376, 5633.802816901389, 6049.727762250797, 6101.572889897665, 6153.784617538365, 6226.696122434728], "value": 4882.5381909248335, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [898.5327629632591, 920.0339643374455, 921.9216366608838, 924.579316411033, 929.8140371925616, 16670.58323541912, 18213.271469141237, 18760.0, 18826.905865470686, 18941.90529047356, 15941.601459963478, 16177.19114044296, 18658.906705466452, 19586.11862466189, 20846.166153353886, 1000.8940121166116, 1023.1303392116679, 1025.5026312238558, 1026.1021606094666, 1032.9173666106694, 1023.8310678738, 1027.4708525109575, 1029.1303848248256, 1038.376973815712, 1041.4584416103621, 772.5712229712507, 1047.9266451348408, 1092.956281748729, 1093.3658478082732, 1093.8031154392206, 1072.2333531524957, 1073.6456969200144, 1079.3901445683164, 1081.7944590527775, 1082.967510974672, 793.821390187209, 794.047143427883, 794.4002278279862, 795.026093036032, 795.6658203554521, 18489.352872649393, 18564.721529177074, 18890.905545472186, 18963.146658400266, 19535.120919558653, 17241.741373879402, 17263.74104388435, 17610.559736006573, 17662.470125896245, 17739.46781596554, 15920.203989800579, 15950.202489875459, 15969.36122555108, 16052.357905683759, 16076.59808504785, 3387.0685561470546, 3392.694657480814, 3450.4996775467666, 3454.360943225486, 3462.23830757233, 128910.0, 129718.0, 134783.0, 135907.0, 136324.0, 15568.299426525833, 15634.921825391028, 15923.124228167562, 15983.600409989844, 16196.514104576881, 30098.398032039237, 31405.0, 31453.37093258122, 31535.0, 31584.36831263362, 8486.0, 8656.09111043344, 8733.737987860375, 8794.384393092421, 8853.247473964786, 4201.6008479194425, 4246.766427846436, 4272.893177670521, 4298.505671847715, 4304.074623955865, 4843.757812109451, 5012.373453318335, 5060.2156665716875, 5078.289039534468, 5085.542301192911, 891.9375643705008, 929.1266209762802, 933.0622724162174, 1046.0794500839365, 1070.5771220368067, 626.786892456567, 688.198249039873, 694.7603076938448, 703.0930100170825, 708.1360739897317, 11215.71960700989, 11298.717532061764, 11707.2975621463, 11939.641810745692, 12336.75326493465, 664.7008846019311, 685.5715178013742, 687.594319351585, 696.5785699651739, 700.8948657701386, 5120.820771273062, 5273.709945952933, 5277.419483856773, 5336.306280183551, 5490.670559766427, 97641.0235897632, 100115.0, 102826.48586757018, 106788.0, 107643.0, 94192.0, 97334.0, 98227.50886245523, 98824.50587747015, 98946.5052674732, 76896.61551692318, 77722.2227777715, 80084.0, 81521.77717334245, 83962.0, 10261.743456413351, 10371.429571373646, 10474.790504190027, 10705.625303114664, 10853.240273180802, 33201.169970750925, 33339.49990750092, 35250.823745881105, 35906.64093359033, 36469.0, 44995.0, 46131.0, 46534.30198547092, 64891.67554162199, 65155.674221628586, 17562.736558951372, 18509.444716658523, 18866.622667546297, 19086.713699294243, 19403.902980485007, 19993.400197994084, 20488.38534843957, 21453.249136280156, 21761.891190543945, 21783.455413614152, 15396.84603153954, 17119.0, 17725.822741772416, 19241.4227573173, 19643.70534442013, 17695.203715832562, 17903.10484475745, 19151.13819878081, 19156.23375064982, 19737.40787776369, 18453.0, 18679.81320186835, 19105.522361941064, 19115.904420477807, 19450.124744386252, 12895.613131606067, 13734.45062197501, 14287.42850285977, 14443.133411995314, 14516.491922783074, 13926.0, 14278.571642850731, 14324.14055156694, 14364.569062928129, 14496.927515362355], "value": 8884.449099584212, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1530.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 109.0, 101.0, 110.0, 108.0, 109.0, 113.0, 102.0, 108.0, 110.0], "value": 106.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 119.0, 153.0, 175.0, 125.0, 151.0, 143.0, 118.0, 168.0, 119.0], "value": 141.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 163.0, 164.0, 169.0, 162.0, 168.0, 166.0, 163.0], "value": 164.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 168.0, 75.0, 77.0, 76.0, 76.0, 99.0, 77.0], "value": 87.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 140.0, 81.0, 82.0, 81.0, 135.0, 81.0, 82.0, 132.0], "value": 97.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 115.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 150.0, 150.0, 150.0, 152.0, 150.0, 150.0, 149.0, 151.0], "value": 150.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 93.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 55.0, 58.0, 57.0, 58.0, 59.0, 58.0, 56.0, 60.0], "value": 57.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 54.0, 55.0, 55.0, 54.0, 55.0, 54.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 80.0, 77.0, 77.0, 89.0, 78.0, 76.0, 77.0, 77.0, 81.0], "value": 79.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 131.0, 126.0, 126.0, 123.0, 141.0, 129.0, 121.0, 136.0, 170.0], "value": 133.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 179.0, 183.0, 178.0, 182.0, 181.0, 180.0, 177.0, 180.0], "value": 180.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 74.0, 71.0, 68.0, 69.0, 69.0, 69.0, 67.0, 67.0], "value": 69.1, "unit": "score"}]}]} 10:17:46 INFO - Return code: 0 10:17:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:17:46 INFO - # TBPL SUCCESS # 10:17:46 INFO - Running post-action listener: _package_coverage_data 10:17:46 INFO - Running post-action listener: _resource_record_post_action 10:17:46 INFO - [mozharness: 2017-07-18 17:17:46.383822Z] Finished run-tests step (success) 10:17:46 INFO - Running post-run listener: _resource_record_post_run 10:17:46 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 147456; Write bytes: 446300160; Read time: 324; Write time: 241032 10:17:46 INFO - TinderboxPrint: CPU usage
13.1% 10:17:46 INFO - TinderboxPrint: I/O read bytes / time
147,456 / 324 10:17:46 INFO - TinderboxPrint: I/O write bytes / time
446,300,160 / 241,032 10:17:46 INFO - TinderboxPrint: CPU idle
8,253.4 (86.9%) 10:17:46 INFO - TinderboxPrint: CPU user
1,197.8 (12.6%) 10:17:46 INFO - TinderboxPrint: Swap in / out
0 / 0 10:17:46 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 115249152; Read time: 140; Write time: 86572 10:17:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:17:46 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 139264; Write bytes: 331051008; Read time: 168; Write time: 154460 10:17:47 INFO - Running post-run listener: _upload_blobber_files 10:17:47 INFO - Blob upload gear active. 10:17:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:17:47 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 10:17:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:17:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:17:47 INFO - Traceback (most recent call last): 10:17:47 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 10:17:47 INFO - main() 10:17:47 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 10:17:47 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 10:17:47 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 10:17:47 INFO - return set(response.json().get('whitelist', [])) 10:17:47 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 10:17:47 INFO - return json.loads(self.text or self.content, **kwargs) 10:17:47 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/__init__.py", line 488, in loads 10:17:47 INFO - return _default_decoder.decode(s) 10:17:47 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 370, in decode 10:17:47 INFO - obj, end = self.raw_decode(s) 10:17:47 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 389, in raw_decode 10:17:47 INFO - return self.scan_once(s, idx=_w(s, idx).end()) 10:17:47 INFO - simplejson.scanner.JSONDecodeError: Expecting value: line 1 column 1 (char 0) 10:17:47 ERROR - Return code: 1 10:17:47 INFO - Setting buildbot property blobber_files to {} 10:17:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:17:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:17:47 INFO - Contents: 10:17:47 INFO - blobber_files:{} 10:17:47 INFO - Running post-run listener: copy_logs_to_upload_dir 10:17:47 INFO - Copying logs to upload dir... 10:17:47 INFO - mkdir: /builds/slave/test/build/upload/logs 10:17:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.549716 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-07-18 10:17:47.403705) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-18 10:17:47.412342) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011235 build_url: 'https://queue.taskcluster.net/v1/task/Zr7KnPKKQ_OSHQjb4HXsZg/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 10:17:47.466043) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 10:17:47.466425) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1500396441.838343-1937613806 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004459 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-18 10:17:47.515921) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-18 10:17:47.516252) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-18 10:17:47.516593) ========= ========= Total master_lag: 0.20 =========