builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-033 starttime: 1500224340.31 results: success (0) revision: 5f44d10bacca2d693413b529e0caadc73e634e1e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-16 09:59:00.306941) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-16 09:59:00.308342) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-16 09:59:00.308640) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-16 09:59:00.332769) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 09:59:00.333060) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-16 09:59: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% 46.0M=0s 2017-07-16 09:59:00 (46.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130328 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 09:59:00.482512) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 09:59:00.482824) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016489 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 09:59:00.550392) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-16 09:59:00.550914) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5f44d10bacca2d693413b529e0caadc73e634e1e --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 5f44d10bacca2d693413b529e0caadc73e634e1e --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=edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-16 09:59:00,732 truncating revision to first 12 chars 2017-07-16 09:59:00,733 Setting DEBUG logging. 2017-07-16 09:59:00,733 attempt 1/10 2017-07-16 09:59:00,733 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5f44d10bacca?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-16 09:59:01,648 unpacking tar archive at: mozilla-central-5f44d10bacca/testing/mozharness/ program finished with exit code 0 elapsedTime=1.267248 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-07-16 09:59:01.834504) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-16 09:59:01.837818) ========= script_repo_revision: 5f44d10bacca2d693413b529e0caadc73e634e1e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-16 09:59:01.838195) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-16 09:59:01.838454) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-16 09:59:01.846621) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-07-16 09:59:01.846890) ========= /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=edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:59:02 INFO - MultiFileLogger online at 20170716 09:59:02 in /builds/slave/test 09:59: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:59:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:59:02 INFO - {'append_to_log': False, 09:59:02 INFO - 'base_work_dir': '/builds/slave/test', 09:59:02 INFO - 'blob_upload_branch': 'Firefox', 09:59:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:59:02 INFO - 'branch': 'Firefox', 09:59:02 INFO - 'buildbot_json_path': 'buildprops.json', 09:59:02 INFO - 'code_coverage': False, 09:59:02 INFO - 'config_files': ('talos/linux_config.py',), 09:59:02 INFO - 'default_actions': ('clobber', 09:59:02 INFO - 'read-buildbot-config', 09:59:02 INFO - 'download-and-extract', 09:59:02 INFO - 'populate-webroot', 09:59:02 INFO - 'create-virtualenv', 09:59:02 INFO - 'install', 09:59:02 INFO - 'setup-mitmproxy', 09:59:02 INFO - 'run-tests'), 09:59:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:59:02 INFO - 'disable_ccov_upload': False, 09:59:02 INFO - 'download_minidump_stackwalk': True, 09:59:02 INFO - 'download_symbols': 'ondemand', 09:59:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:59:02 INFO - 'tooltool.py': '/tools/tooltool.py', 09:59:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:59:02 INFO - '/tools/misc-python/virtualenv.py')}, 09:59:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:59:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:59:02 INFO - 'gecko_profile': False, 09:59:02 INFO - 'gecko_profile_interval': 0, 09:59:02 INFO - 'installer_path': 'installer.exe', 09:59:02 INFO - 'log_level': 'info', 09:59:02 INFO - 'log_name': 'talos', 09:59:02 INFO - 'log_to_console': True, 09:59:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:59:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:59:02 INFO - 'opt_config_files': (), 09:59:02 INFO - 'pip_index': False, 09:59:02 INFO - 'suite': 'dromaeojs-e10s', 09:59:02 INFO - 'system_bits': '32', 09:59:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:59:02 INFO - 'title': 'talos-linux64-ix-033', 09:59:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:59:02 INFO - 'use_talos_json': True, 09:59:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:59:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:59:02 INFO - 'work_dir': 'build'} 09:59:02 INFO - [mozharness: 2017-07-16 16:59:02.129822Z] Running clobber step. 09:59:02 INFO - Running pre-action listener: _resource_record_pre_action 09:59:02 INFO - Running main action method: clobber 09:59:02 INFO - rmtree: /builds/slave/test/build 09:59:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:59:03 INFO - Running post-action listener: _resource_record_post_action 09:59:03 INFO - [mozharness: 2017-07-16 16:59:03.673997Z] Finished clobber step (success) 09:59:03 INFO - [mozharness: 2017-07-16 16:59:03.674099Z] Running read-buildbot-config step. 09:59:03 INFO - Running pre-action listener: _resource_record_pre_action 09:59:03 INFO - Running main action method: read_buildbot_config 09:59:03 INFO - Using buildbot properties: 09:59:03 INFO - { 09:59:03 INFO - "project": "", 09:59:03 INFO - "product": "firefox", 09:59:03 INFO - "who": "ffxbld@noreply.mozilla.org", 09:59:03 INFO - "installer_path": "public/build/target.tar.bz2", 09:59:03 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 09:59:03 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:59:03 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 09:59:03 INFO - "stage_platform": "linux64-stylo", 09:59:03 INFO - "basedir": "/builds/slave/test", 09:59:03 INFO - "buildnumber": 30, 09:59:03 INFO - "platform": "ubuntu64_hw_stylo", 09:59:03 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:59:03 INFO - "slavebuilddir": "test", 09:59:03 INFO - "taskId": "CkYwVi10T7iRhckIiCkd1Q", 09:59:03 INFO - "branch": "mozilla-central", 09:59:03 INFO - "script_repo_revision": "production", 09:59:03 INFO - "revision": "5f44d10bacca2d693413b529e0caadc73e634e1e", 09:59:03 INFO - "slavename": "talos-linux64-ix-033", 09:59:03 INFO - "repo_path": "mozilla-central" 09:59:03 INFO - } 09:59:03 INFO - Finding installer, test and symbols from parent task. 09:59:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CkYwVi10T7iRhckIiCkd1Q'}, attempt #1 09:59:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CkYwVi10T7iRhckIiCkd1Q'}, attempt #1 09:59:04 INFO - Task dependencies: BQEDOoXIQH6z0T86mY66Ow 09:59:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow'}, attempt #1 09:59:05 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.tar.bz2 09:59:05 INFO - Set test_url: https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/test_packages.json 09:59:05 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.crashreporter-symbols.zip 09:59:05 INFO - Running post-action listener: _resource_record_post_action 09:59:05 INFO - [mozharness: 2017-07-16 16:59:05.032097Z] Finished read-buildbot-config step (success) 09:59:05 INFO - [mozharness: 2017-07-16 16:59:05.032319Z] Running download-and-extract step. 09:59:05 INFO - Running pre-action listener: _resource_record_pre_action 09:59:05 INFO - Running main action method: download_and_extract 09:59:05 INFO - mkdir: /builds/slave/test/build/tests 09:59:05 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:59:05 INFO - trying https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.test_packages.json 09:59:05 INFO - Downloading https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:59:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:59:05 INFO - Downloaded 989 bytes. 09:59:05 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:59:05 INFO - Using the following test package requirements: 09:59:05 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:59:05 INFO - u'common': [u'target.common.tests.zip'], 09:59:05 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:59:05 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:59:05 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:59:05 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:59:05 INFO - u'mozbase': [u'target.common.tests.zip'], 09:59:05 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:59:05 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:59:05 INFO - u'web-platform': [u'target.common.tests.zip', 09:59:05 INFO - u'target.web-platform.tests.tar.gz'], 09:59:05 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:59:05 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:59:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.common.tests.zip 09:59:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:59:05 INFO - Fetch https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.common.tests.zip into memory 09:59:08 INFO - Content-Length response header: 72757742 09:59:08 INFO - Bytes received: 72757742 09:59:19 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:59:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.common.tests.zip 09:59:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:59:19 INFO - Fetch https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.common.tests.zip into memory 09:59:22 INFO - Content-Length response header: 72757742 09:59:22 INFO - Bytes received: 72757742 09:59:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.talos.tests.zip 09:59:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:59:34 INFO - Fetch https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.talos.tests.zip into memory 09:59:35 INFO - Content-Length response header: 14495033 09:59:35 INFO - Bytes received: 14495033 09:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:36 INFO - trying https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.tar.bz2 09:59:36 INFO - Downloading https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:59:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:59:39 INFO - Downloaded 61890864 bytes. 09:59:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.tar.bz2 09:59:39 INFO - mkdir: /builds/slave/test/properties 09:59:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:59:39 INFO - Writing to file /builds/slave/test/properties/build_url 09:59:39 INFO - Contents: 09:59:39 INFO - build_url:https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.tar.bz2 09:59:39 INFO - Running post-action listener: _resource_record_post_action 09:59:39 INFO - Running post-action listener: set_extra_try_arguments 09:59:39 INFO - [mozharness: 2017-07-16 16:59:39.506233Z] Finished download-and-extract step (success) 09:59:39 INFO - [mozharness: 2017-07-16 16:59:39.506462Z] Running populate-webroot step. 09:59:39 INFO - Running pre-action listener: _resource_record_pre_action 09:59:39 INFO - Running main action method: populate_webroot 09:59:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:59:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:59:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:59:39 INFO - '--tppagecycles', 09:59:39 INFO - '7'], 09:59:39 INFO - 'tests': ['tsvgm']}}, 09:59:39 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:59:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:59:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:59:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:59:39 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:59:39 INFO - 'talos_options': ['--disable-e10s'], 09:59:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:59:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:39 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:59:39 INFO - 'talos_options': ['--disable-e10s'], 09:59:39 INFO - 'tests': ['damp', 'tps']}, 09:59:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:59:39 INFO - 'tests': ['damp', 'tps']}, 09:59:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:59:39 INFO - 'tests': ['dromaeo_dom']}, 09:59:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:59:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:59:39 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:59:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:59:39 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:59:39 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:59:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:59:39 INFO - 'tests': ['a11yr', 09:59:39 INFO - 'ts_paint', 09:59:39 INFO - 'tpaint', 09:59:39 INFO - 'sessionrestore', 09:59:39 INFO - 'sessionrestore_no_auto_restore', 09:59:39 INFO - 'tabpaint']}, 09:59:39 INFO - 'other-e10s': {'tests': ['a11yr', 09:59:39 INFO - 'ts_paint', 09:59:39 INFO - 'tpaint', 09:59:39 INFO - 'sessionrestore', 09:59:39 INFO - 'sessionrestore_many_windows', 09:59:39 INFO - 'sessionrestore_no_auto_restore', 09:59:39 INFO - 'tabpaint']}, 09:59:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:59:39 INFO - 'ts_paint', 09:59:39 INFO - 'tpaint', 09:59:39 INFO - 'sessionrestore', 09:59:39 INFO - 'sessionrestore_many_windows', 09:59:39 INFO - 'sessionrestore_no_auto_restore', 09:59:39 INFO - 'tabpaint']}, 09:59:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:59:39 INFO - 'ts_paint', 09:59:39 INFO - 'tpaint', 09:59:39 INFO - 'sessionrestore', 09:59:39 INFO - 'sessionrestore_many_windows', 09:59:39 INFO - 'sessionrestore_no_auto_restore', 09:59:39 INFO - 'tabpaint']}, 09:59:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:59:39 INFO - 'tests': ['a11yr', 09:59:39 INFO - 'ts_paint', 09:59:39 INFO - 'tpaint', 09:59:39 INFO - 'sessionrestore', 09:59:39 INFO - 'sessionrestore_no_auto_restore', 09:59:39 INFO - 'tabpaint']}, 09:59:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:59:39 INFO - 'tests': ['a11yr', 09:59:39 INFO - 'ts_paint', 09:59:39 INFO - 'tpaint', 09:59:39 INFO - 'sessionrestore', 09:59:39 INFO - 'sessionrestore_no_auto_restore', 09:59:39 INFO - 'tabpaint']}, 09:59:39 INFO - 'perf-reftest': {'tests': ['bloom_basic']}, 09:59:39 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 09:59:39 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 09:59:39 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:59:39 INFO - 'talos_options': ['--mitmproxy', 09:59:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:59:39 INFO - '--firstNonBlankPaint'], 09:59:39 INFO - 'tests': ['quantum_pageload_google', 09:59:39 INFO - 'quantum_pageload_youtube', 09:59:39 INFO - 'quantum_pageload_amazon', 09:59:39 INFO - 'quantum_pageload_facebook']}, 09:59:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:59:39 INFO - 'tests': ['tsvgx', 09:59:39 INFO - 'tsvgr_opacity', 09:59:39 INFO - 'tart', 09:59:39 INFO - 'tscrollx', 09:59:39 INFO - 'cart', 09:59:39 INFO - 'tsvg_static']}, 09:59:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:59:39 INFO - 'tsvgr_opacity', 09:59:39 INFO - 'tart', 09:59:39 INFO - 'tscrollx', 09:59:39 INFO - 'cart', 09:59:39 INFO - 'tsvg_static']}, 09:59:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:59:39 INFO - 'talos_options': ['--disable-e10s'], 09:59:39 INFO - 'tests': ['tp5o']}, 09:59:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:59:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:59:39 INFO - 'talos_options': ['--disable-e10s', 09:59:39 INFO - '--xperf_path', 09:59:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:39 INFO - 'tests': ['tp5n']}, 09:59:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:59:39 INFO - 'talos_options': ['--xperf_path', 09:59:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:39 INFO - 'tests': ['tp5n']}}, 09:59:39 INFO - 'talos.zip': {'path': '', 09:59:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:59:39 INFO - Running post-action listener: _resource_record_post_action 09:59:39 INFO - [mozharness: 2017-07-16 16:59:39.528367Z] Finished populate-webroot step (success) 09:59:39 INFO - [mozharness: 2017-07-16 16:59:39.528458Z] Running create-virtualenv step. 09:59:39 INFO - Running pre-action listener: _resource_record_pre_action 09:59:39 INFO - Running main action method: create_virtualenv 09:59:39 INFO - Creating virtualenv /builds/slave/test/build/venv 09:59:39 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:59:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:59:39 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:59:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:59:39 INFO - Using real prefix '/usr' 09:59:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:59:40 INFO - Installing distribute.............................................................................................................................................................................................done. 09:59:42 INFO - Installing pip.................done. 09:59:42 INFO - Return code: 0 09:59:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:59:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:59:42 INFO - Reading from file tmpfile_stdout 09:59:42 INFO - Output received: 09:59:42 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:59:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:59:42 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:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:42 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:59:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:42 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 0x285b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a27a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bccb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc9d00>, '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 0x2bcac00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bcb090>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', '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:59:42 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:59:42 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:59:42 INFO - Using env: {'DISPLAY': ':0', 09:59:42 INFO - 'HOME': '/home/cltbld', 09:59:42 INFO - 'LANG': 'en_US.UTF-8', 09:59:42 INFO - 'LANGUAGE': 'en_US:en', 09:59:42 INFO - 'LOGNAME': 'cltbld', 09:59:42 INFO - 'MAIL': '/var/mail/cltbld', 09:59:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:42 INFO - 'MOZ_NO_REMOTE': '1', 09:59:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:42 INFO - 'NO_EM_RESTART': '1', 09:59:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:42 INFO - 'PWD': '/builds/slave/test', 09:59:42 INFO - 'SHELL': '/bin/bash', 09:59:42 INFO - 'SHLVL': '1', 09:59:42 INFO - 'TERM': 'linux', 09:59:42 INFO - 'TMOUT': '86400', 09:59:42 INFO - 'USER': 'cltbld', 09:59:42 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', 09:59:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:42 INFO - '_': '/tools/buildbot/bin/python'} 09:59:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:42 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:59:42 INFO - Cleaning up... 09:59:42 INFO - Return code: 0 09:59:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:59:42 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:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:42 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:59:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:42 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 0x285b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a27a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bccb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc9d00>, '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 0x2bcac00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bcb090>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', '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:59:42 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:59:42 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:59:42 INFO - Using env: {'DISPLAY': ':0', 09:59:42 INFO - 'HOME': '/home/cltbld', 09:59:42 INFO - 'LANG': 'en_US.UTF-8', 09:59:42 INFO - 'LANGUAGE': 'en_US:en', 09:59:42 INFO - 'LOGNAME': 'cltbld', 09:59:42 INFO - 'MAIL': '/var/mail/cltbld', 09:59:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:42 INFO - 'MOZ_NO_REMOTE': '1', 09:59:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:42 INFO - 'NO_EM_RESTART': '1', 09:59:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:42 INFO - 'PWD': '/builds/slave/test', 09:59:42 INFO - 'SHELL': '/bin/bash', 09:59:42 INFO - 'SHLVL': '1', 09:59:42 INFO - 'TERM': 'linux', 09:59:42 INFO - 'TMOUT': '86400', 09:59:42 INFO - 'USER': 'cltbld', 09:59:42 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', 09:59:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:42 INFO - '_': '/tools/buildbot/bin/python'} 09:59:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:42 INFO - Downloading/unpacking psutil>=3.1.1 09:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:59:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:43 INFO - Installing collected packages: psutil 09:59:43 INFO - Running setup.py install for psutil 09:59:44 INFO - building 'psutil._psutil_linux' extension 09:59:44 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:59:44 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:59:44 INFO - building 'psutil._psutil_posix' extension 09:59:44 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:59:44 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:59:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:44 INFO - Successfully installed psutil 09:59:44 INFO - Cleaning up... 09:59:44 INFO - Return code: 0 09:59:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:59:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x285b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a27a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bccb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc9d00>, '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 0x2bcac00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bcb090>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', '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:59:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:59:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:59:44 INFO - Using env: {'DISPLAY': ':0', 09:59:44 INFO - 'HOME': '/home/cltbld', 09:59:44 INFO - 'LANG': 'en_US.UTF-8', 09:59:44 INFO - 'LANGUAGE': 'en_US:en', 09:59:44 INFO - 'LOGNAME': 'cltbld', 09:59:44 INFO - 'MAIL': '/var/mail/cltbld', 09:59:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:44 INFO - 'MOZ_NO_REMOTE': '1', 09:59:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:44 INFO - 'NO_EM_RESTART': '1', 09:59:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:44 INFO - 'PWD': '/builds/slave/test', 09:59:44 INFO - 'SHELL': '/bin/bash', 09:59:44 INFO - 'SHLVL': '1', 09:59:44 INFO - 'TERM': 'linux', 09:59:44 INFO - 'TMOUT': '86400', 09:59:44 INFO - 'USER': 'cltbld', 09:59:44 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', 09:59:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:44 INFO - '_': '/tools/buildbot/bin/python'} 09:59:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:59: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:59: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:59: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:59: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:59:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:59:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:59:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:59:45 INFO - Installing collected packages: mozsystemmonitor 09:59:45 INFO - Running setup.py install for mozsystemmonitor 09:59:45 INFO - Successfully installed mozsystemmonitor 09:59:45 INFO - Cleaning up... 09:59:45 INFO - Return code: 0 09:59:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:59:45 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:59:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:45 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:59:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:45 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 0x285b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a27a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bccb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc9d00>, '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 0x2bcac00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bcb090>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', '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:59:45 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:59:45 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:59:45 INFO - Using env: {'DISPLAY': ':0', 09:59:45 INFO - 'HOME': '/home/cltbld', 09:59:45 INFO - 'LANG': 'en_US.UTF-8', 09:59:45 INFO - 'LANGUAGE': 'en_US:en', 09:59:45 INFO - 'LOGNAME': 'cltbld', 09:59:45 INFO - 'MAIL': '/var/mail/cltbld', 09:59:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:45 INFO - 'MOZ_NO_REMOTE': '1', 09:59:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:45 INFO - 'NO_EM_RESTART': '1', 09:59:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:45 INFO - 'PWD': '/builds/slave/test', 09:59:45 INFO - 'SHELL': '/bin/bash', 09:59:45 INFO - 'SHLVL': '1', 09:59:45 INFO - 'TERM': 'linux', 09:59:45 INFO - 'TMOUT': '86400', 09:59:45 INFO - 'USER': 'cltbld', 09:59:45 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', 09:59:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:45 INFO - '_': '/tools/buildbot/bin/python'} 09:59:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:46 INFO - Downloading/unpacking jsonschema==2.5.1 09:59:46 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:59:46 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:59:46 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:59:46 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:59:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:59:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:47 INFO - Downloading functools32-3.2.3-2.tar.gz 09:59:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:59:47 INFO - warning: no files found matching '*.txt' 09:59:47 INFO - no previously-included directories found matching 'build' 09:59:47 INFO - no previously-included directories found matching 'dist' 09:59:47 INFO - no previously-included directories found matching '.git*' 09:59:47 INFO - Installing collected packages: jsonschema, functools32 09:59:47 INFO - Running setup.py install for functools32 09:59:47 INFO - warning: no files found matching '*.txt' 09:59:47 INFO - no previously-included directories found matching 'build' 09:59:47 INFO - no previously-included directories found matching 'dist' 09:59:47 INFO - no previously-included directories found matching '.git*' 09:59:47 INFO - Successfully installed jsonschema functools32 09:59:47 INFO - Cleaning up... 09:59:47 INFO - Return code: 0 09:59:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:59:47 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:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:47 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:59:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:47 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 0x285b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a27a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bccb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc9d00>, '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 0x2bcac00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bcb090>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', '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:59:47 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:59:47 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:59:47 INFO - Using env: {'DISPLAY': ':0', 09:59:47 INFO - 'HOME': '/home/cltbld', 09:59:47 INFO - 'LANG': 'en_US.UTF-8', 09:59:47 INFO - 'LANGUAGE': 'en_US:en', 09:59:47 INFO - 'LOGNAME': 'cltbld', 09:59:47 INFO - 'MAIL': '/var/mail/cltbld', 09:59:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:47 INFO - 'MOZ_NO_REMOTE': '1', 09:59:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:47 INFO - 'NO_EM_RESTART': '1', 09:59:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:47 INFO - 'PWD': '/builds/slave/test', 09:59:47 INFO - 'SHELL': '/bin/bash', 09:59:47 INFO - 'SHLVL': '1', 09:59:47 INFO - 'TERM': 'linux', 09:59:47 INFO - 'TMOUT': '86400', 09:59:47 INFO - 'USER': 'cltbld', 09:59:47 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', 09:59:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:47 INFO - '_': '/tools/buildbot/bin/python'} 09:59:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:59:47 INFO - Cleaning up... 09:59:47 INFO - Return code: 0 09:59:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:59:47 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:59:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:47 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:59:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:47 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 0x285b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a27a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bccb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc9d00>, '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 0x2bcac00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bcb090>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', '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:59:47 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:59:47 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:59:47 INFO - Using env: {'DISPLAY': ':0', 09:59:47 INFO - 'HOME': '/home/cltbld', 09:59:47 INFO - 'LANG': 'en_US.UTF-8', 09:59:47 INFO - 'LANGUAGE': 'en_US:en', 09:59:47 INFO - 'LOGNAME': 'cltbld', 09:59:47 INFO - 'MAIL': '/var/mail/cltbld', 09:59:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:47 INFO - 'MOZ_NO_REMOTE': '1', 09:59:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:47 INFO - 'NO_EM_RESTART': '1', 09:59:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:47 INFO - 'PWD': '/builds/slave/test', 09:59:47 INFO - 'SHELL': '/bin/bash', 09:59:47 INFO - 'SHLVL': '1', 09:59:47 INFO - 'TERM': 'linux', 09:59:47 INFO - 'TMOUT': '86400', 09:59:47 INFO - 'USER': 'cltbld', 09:59:47 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', 09:59:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:47 INFO - '_': '/tools/buildbot/bin/python'} 09:59:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:48 INFO - Downloading/unpacking blobuploader==1.2.4 09:59:48 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:59:48 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:59:48 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:59:48 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:59:49 INFO - Downloading blobuploader-1.2.4.tar.gz 09:59:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:59:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:59:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:49 INFO - Downloading docopt-0.6.1.tar.gz 09:59:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:59:49 INFO - Installing collected packages: blobuploader, requests, docopt 09:59:49 INFO - Running setup.py install for blobuploader 09:59:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:59:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:59:49 INFO - Running setup.py install for requests 09:59:50 INFO - Running setup.py install for docopt 09:59:50 INFO - Successfully installed blobuploader requests docopt 09:59:50 INFO - Cleaning up... 09:59:50 INFO - Return code: 0 09:59:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59:50 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:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:50 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:59:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:50 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 0x285b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a27a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bccb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc9d00>, '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 0x2bcac00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bcb090>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', '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:59:50 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:59:50 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:59:50 INFO - Using env: {'DISPLAY': ':0', 09:59:50 INFO - 'HOME': '/home/cltbld', 09:59:50 INFO - 'LANG': 'en_US.UTF-8', 09:59:50 INFO - 'LANGUAGE': 'en_US:en', 09:59:50 INFO - 'LOGNAME': 'cltbld', 09:59:50 INFO - 'MAIL': '/var/mail/cltbld', 09:59:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:50 INFO - 'MOZ_NO_REMOTE': '1', 09:59:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:50 INFO - 'NO_EM_RESTART': '1', 09:59:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:50 INFO - 'PWD': '/builds/slave/test', 09:59:50 INFO - 'SHELL': '/bin/bash', 09:59:50 INFO - 'SHLVL': '1', 09:59:50 INFO - 'TERM': 'linux', 09:59:50 INFO - 'TMOUT': '86400', 09:59:50 INFO - 'USER': 'cltbld', 09:59:50 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', 09:59:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:50 INFO - '_': '/tools/buildbot/bin/python'} 09:59:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:59:50 INFO - Running setup.py (path:/tmp/pip-wDbQIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:59:50 INFO - Running setup.py (path:/tmp/pip-dCltJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:59:50 INFO - Running setup.py (path:/tmp/pip-2Ak31i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:59:50 INFO - Running setup.py (path:/tmp/pip-7WzfZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:59:50 INFO - Running setup.py (path:/tmp/pip-FSN4nU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:59:50 INFO - Running setup.py (path:/tmp/pip-XV5Fiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:59:51 INFO - Running setup.py (path:/tmp/pip-CI_tPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:59:51 INFO - Running setup.py (path:/tmp/pip-QDx8FT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:59:51 INFO - Running setup.py (path:/tmp/pip-zLQfpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:59:51 INFO - Running setup.py (path:/tmp/pip-b8WKp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:59:51 INFO - Running setup.py (path:/tmp/pip-fAggV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:59:51 INFO - Running setup.py (path:/tmp/pip-DfrRL0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:59:51 INFO - Running setup.py (path:/tmp/pip-oHd9eN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:59:51 INFO - Running setup.py (path:/tmp/pip-qFHYZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:51 INFO - Running setup.py (path:/tmp/pip-3HYREb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:59:51 INFO - Running setup.py (path:/tmp/pip-BJSo17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:59:51 INFO - Running setup.py (path:/tmp/pip-CVs0fC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:59:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:59:51 INFO - Running setup.py install for manifestparser 09:59:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:59:51 INFO - Running setup.py install for mozcrash 09:59:52 INFO - Running setup.py install for mozdebug 09:59:52 INFO - Running setup.py install for mozdevice 09:59:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:59:52 INFO - Running setup.py install for mozfile 09:59:52 INFO - Running setup.py install for mozhttpd 09:59:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:59:52 INFO - Running setup.py install for mozinfo 09:59:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:59:52 INFO - Running setup.py install for mozInstall 09:59:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:59:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:59:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:59:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:59:52 INFO - Running setup.py install for mozleak 09:59:52 INFO - Running setup.py install for mozlog 09:59:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:59:52 INFO - Running setup.py install for moznetwork 09:59:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:59:52 INFO - Running setup.py install for mozprocess 09:59:53 INFO - Running setup.py install for mozprofile 09:59:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:59:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:59:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:59:53 INFO - Running setup.py install for mozrunner 09:59:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:59:53 INFO - Running setup.py install for mozscreenshot 09:59:53 INFO - Running setup.py install for moztest 09:59:53 INFO - Running setup.py install for mozversion 09:59:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:59:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:59:53 INFO - Cleaning up... 09:59:53 INFO - Return code: 0 09:59:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:53 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 0x285b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a27a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bccb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc9d00>, '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 0x2bcac00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bcb090>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', '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:59:53 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:59:53 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:59:53 INFO - Using env: {'DISPLAY': ':0', 09:59:53 INFO - 'HOME': '/home/cltbld', 09:59:53 INFO - 'LANG': 'en_US.UTF-8', 09:59:53 INFO - 'LANGUAGE': 'en_US:en', 09:59:53 INFO - 'LOGNAME': 'cltbld', 09:59:53 INFO - 'MAIL': '/var/mail/cltbld', 09:59:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:53 INFO - 'MOZ_NO_REMOTE': '1', 09:59:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:53 INFO - 'NO_EM_RESTART': '1', 09:59:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:53 INFO - 'PWD': '/builds/slave/test', 09:59:53 INFO - 'SHELL': '/bin/bash', 09:59:53 INFO - 'SHLVL': '1', 09:59:53 INFO - 'TERM': 'linux', 09:59:53 INFO - 'TMOUT': '86400', 09:59:53 INFO - 'USER': 'cltbld', 09:59:53 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', 09:59:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:53 INFO - '_': '/tools/buildbot/bin/python'} 09:59:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:59:53 INFO - Running setup.py (path:/tmp/pip-fFTS6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:59:53 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:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:59:53 INFO - Running setup.py (path:/tmp/pip-dfv_IN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:59:53 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:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:59:53 INFO - Running setup.py (path:/tmp/pip-CKEerD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:59:53 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:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:59:53 INFO - Running setup.py (path:/tmp/pip-85L7Ll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:59:54 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:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:59:54 INFO - Running setup.py (path:/tmp/pip-uokMgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:59:54 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:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:59:54 INFO - Running setup.py (path:/tmp/pip-ckqmSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:59:54 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:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:59:54 INFO - Running setup.py (path:/tmp/pip-i4d5J4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:59:54 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:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:59:54 INFO - Running setup.py (path:/tmp/pip-YFn2Nx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:59:54 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:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:59:54 INFO - Running setup.py (path:/tmp/pip-zW0bzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:59:54 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:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:59:54 INFO - Running setup.py (path:/tmp/pip-vO6i2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:59:54 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:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:59:54 INFO - Running setup.py (path:/tmp/pip-4QZX6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:59:54 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:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:59:54 INFO - Running setup.py (path:/tmp/pip-gDDxpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:59:54 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:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:59:54 INFO - Running setup.py (path:/tmp/pip-FhL4OR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:59:54 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:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:59:54 INFO - Running setup.py (path:/tmp/pip-x4LM6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:59:54 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:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:54 INFO - Running setup.py (path:/tmp/pip-41ZiZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:54 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:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:59:54 INFO - Running setup.py (path:/tmp/pip-12DPug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:59:54 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:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:59:54 INFO - Running setup.py (path:/tmp/pip-FptMqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:59:55 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:59:55 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:59:55 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:59:55 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:59:55 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:59:55 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:59:55 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:59:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:59:55 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:59:55 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:59:55 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:59:55 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:59:56 INFO - Downloading blessings-1.6.tar.gz 09:59:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:59:56 INFO - Installing collected packages: blessings 09:59:56 INFO - Running setup.py install for blessings 09:59:56 INFO - Successfully installed blessings 09:59:56 INFO - Cleaning up... 09:59:56 INFO - Return code: 0 09:59:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:59:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:59:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:59:56 INFO - Reading from file tmpfile_stdout 09:59:56 INFO - Current package versions: 09:59:56 INFO - argparse == 1.2.1 09:59:56 INFO - blessings == 1.6 09:59:56 INFO - blobuploader == 1.2.4 09:59:56 INFO - docopt == 0.6.1 09:59:56 INFO - functools32 == 3.2.3-2 09:59:56 INFO - jsonschema == 2.5.1 09:59:56 INFO - manifestparser == 1.1 09:59:56 INFO - mozInstall == 1.13 09:59:56 INFO - mozcrash == 1.0 09:59:56 INFO - mozdebug == 0.1 09:59:56 INFO - mozdevice == 0.51 09:59:56 INFO - mozfile == 1.2 09:59:56 INFO - mozhttpd == 0.7 09:59:56 INFO - mozinfo == 0.10 09:59:56 INFO - mozleak == 0.1 09:59:56 INFO - mozlog == 3.5 09:59:56 INFO - moznetwork == 0.27 09:59:56 INFO - mozprocess == 0.25 09:59:56 INFO - mozprofile == 0.28 09:59:56 INFO - mozrunner == 6.13 09:59:56 INFO - mozscreenshot == 0.1 09:59:56 INFO - mozsystemmonitor == 0.3 09:59:56 INFO - moztest == 0.8 09:59:56 INFO - mozversion == 1.4 09:59:56 INFO - psutil == 3.1.1 09:59:56 INFO - requests == 1.2.3 09:59:56 INFO - wsgiref == 0.1.2 09:59:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59:56 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:59:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:56 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:59:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:56 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 0x285b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a27a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bccb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc9d00>, '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 0x2bcac00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bcb090>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', '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:59:56 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:59:56 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:59:56 INFO - Using env: {'DISPLAY': ':0', 09:59:56 INFO - 'HOME': '/home/cltbld', 09:59:56 INFO - 'LANG': 'en_US.UTF-8', 09:59:56 INFO - 'LANGUAGE': 'en_US:en', 09:59:56 INFO - 'LOGNAME': 'cltbld', 09:59:56 INFO - 'MAIL': '/var/mail/cltbld', 09:59:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:56 INFO - 'MOZ_NO_REMOTE': '1', 09:59:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:56 INFO - 'NO_EM_RESTART': '1', 09:59:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:56 INFO - 'PWD': '/builds/slave/test', 09:59:56 INFO - 'SHELL': '/bin/bash', 09:59:56 INFO - 'SHLVL': '1', 09:59:56 INFO - 'TERM': 'linux', 09:59:56 INFO - 'TMOUT': '86400', 09:59:56 INFO - 'USER': 'cltbld', 09:59:56 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', 09:59:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:56 INFO - '_': '/tools/buildbot/bin/python'} 09:59:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:56 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:59:56 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:59:56 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:59:56 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:59:56 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:59:56 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:59:56 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:59:56 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:59:56 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:59:56 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:59:56 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:59:56 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:59:56 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:59:56 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:59:57 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:59:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:59:57 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 09:59:57 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:59:57 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:59:57 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:59:57 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:59:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 09:59:58 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:59:58 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:59:58 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:59:58 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:59:58 INFO - Installing collected packages: marionette-driver, simplejson 09:59:58 INFO - Running setup.py install for marionette-driver 09:59:58 INFO - Running setup.py install for simplejson 09:59:58 INFO - building 'simplejson._speedups' extension 09:59:58 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:59:59 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:59:59 INFO - Successfully installed marionette-driver simplejson 09:59:59 INFO - Cleaning up... 09:59:59 INFO - Return code: 0 09:59:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:59:59 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:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:59 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:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:59 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 0x285b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a27a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2bccb60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc9d00>, '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 0x2bcac00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bcb090>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', '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:59:59 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:59:59 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:59:59 INFO - Using env: {'DISPLAY': ':0', 09:59:59 INFO - 'HOME': '/home/cltbld', 09:59:59 INFO - 'LANG': 'en_US.UTF-8', 09:59:59 INFO - 'LANGUAGE': 'en_US:en', 09:59:59 INFO - 'LOGNAME': 'cltbld', 09:59:59 INFO - 'MAIL': '/var/mail/cltbld', 09:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:59 INFO - 'MOZ_NO_REMOTE': '1', 09:59:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:59 INFO - 'NO_EM_RESTART': '1', 09:59:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:59 INFO - 'PWD': '/builds/slave/test', 09:59:59 INFO - 'SHELL': '/bin/bash', 09:59:59 INFO - 'SHLVL': '1', 09:59:59 INFO - 'TERM': 'linux', 09:59:59 INFO - 'TMOUT': '86400', 09:59:59 INFO - 'USER': 'cltbld', 09:59:59 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', 09:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:59 INFO - '_': '/tools/buildbot/bin/python'} 09:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:59:59 INFO - Cleaning up... 09:59:59 INFO - Return code: 0 09:59:59 INFO - Running post-action listener: _resource_record_post_action 09:59:59 INFO - Running post-action listener: _start_resource_monitoring 09:59:59 INFO - Starting resource monitoring. 09:59:59 INFO - [mozharness: 2017-07-16 16:59:59.598781Z] Finished create-virtualenv step (success) 09:59:59 INFO - [mozharness: 2017-07-16 16:59:59.599291Z] Running install step. 09:59:59 INFO - Running pre-action listener: _resource_record_pre_action 09:59:59 INFO - Running main action method: install 09:59:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:59:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:59:59 INFO - Reading from file tmpfile_stdout 09:59:59 INFO - Detecting whether we're running mozinstall >=1.0... 09:59:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:59:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:59:59 INFO - Reading from file tmpfile_stdout 09:59:59 INFO - Output received: 09:59:59 INFO - Usage: mozinstall [options] installer 09:59:59 INFO - Options: 09:59:59 INFO - -h, --help show this help message and exit 09:59:59 INFO - -d DEST, --destination=DEST 09:59:59 INFO - Directory to install application into. [default: 09:59:59 INFO - "/builds/slave/test"] 09:59:59 INFO - --app=APP Application being installed. [default: firefox] 09:59:59 INFO - mkdir: /builds/slave/test/build/application 09:59:59 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:59:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 10:00:17 INFO - Reading from file tmpfile_stdout 10:00:17 INFO - Output received: 10:00:17 INFO - /builds/slave/test/build/application/firefox/firefox 10:00:17 INFO - Running post-action listener: _resource_record_post_action 10:00:17 INFO - [mozharness: 2017-07-16 17:00:17.847726Z] Finished install step (success) 10:00:17 INFO - [mozharness: 2017-07-16 17:00:17.847980Z] Running setup-mitmproxy step. 10:00:17 INFO - Running pre-action listener: _resource_record_pre_action 10:00:17 INFO - Running main action method: setup_mitmproxy 10:00:17 INFO - Skipping: mitmproxy is not required 10:00:17 INFO - Running post-action listener: _resource_record_post_action 10:00:17 INFO - [mozharness: 2017-07-16 17:00:17.849195Z] Finished setup-mitmproxy step (success) 10:00:17 INFO - [mozharness: 2017-07-16 17:00:17.849419Z] Running run-tests step. 10:00:17 INFO - Running pre-action listener: _resource_record_pre_action 10:00:17 INFO - Running pre-action listener: _set_gcov_prefix 10:00:17 INFO - Running main action method: run_tests 10:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:00:17 INFO - Python 2.7.3 10:00:17 INFO - Return code: 0 10:00:17 INFO - grabbing minidump binary from tooltool 10:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:00:17 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 0x2bc9d00>, '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 0x2bcac00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bcb090>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:00:17 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 10:00:17 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 10:00:17 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 10:00:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:00:17 INFO - Return code: 0 10:00:17 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:00:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:00:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:00:17 INFO - ENV: RUST_BACKTRACE is now 1 10:00:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:00:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:00:17 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/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 10:00:17 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/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-033 --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 10:00:17 INFO - Using env: {'DISPLAY': ':0', 10:00:17 INFO - 'HOME': '/home/cltbld', 10:00:17 INFO - 'LANG': 'en_US.UTF-8', 10:00:17 INFO - 'LANGUAGE': 'en_US:en', 10:00:17 INFO - 'LOGNAME': 'cltbld', 10:00:17 INFO - 'MAIL': '/var/mail/cltbld', 10:00:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:00:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:17 INFO - 'MOZ_NO_REMOTE': '1', 10:00:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:00:17 INFO - 'NO_EM_RESTART': '1', 10:00:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:17 INFO - 'PWD': '/builds/slave/test', 10:00:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:00:17 INFO - 'RUST_BACKTRACE': '1', 10:00:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:00:17 INFO - 'SHELL': '/bin/bash', 10:00:17 INFO - 'SHLVL': '1', 10:00:17 INFO - 'TERM': 'linux', 10:00:17 INFO - 'TMOUT': '86400', 10:00:17 INFO - 'USER': 'cltbld', 10:00:17 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480', 10:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:17 INFO - '_': '/tools/buildbot/bin/python'} 10:00:17 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/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 10:00:18 INFO - mozversion application_buildid: 20170716161811 10:00:18 INFO - mozversion application_changeset: 5f44d10bacca2d693413b529e0caadc73e634e1e 10:00:18 INFO - mozversion application_display_name: Nightly 10:00:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:00:18 INFO - mozversion application_name: Firefox 10:00:18 INFO - mozversion application_remotingname: firefox 10:00:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:00:18 INFO - mozversion application_vendor: Mozilla 10:00:18 INFO - mozversion application_version: 56.0a1 10:00:18 INFO - mozversion platform_buildid: 20170716161811 10:00:18 INFO - mozversion platform_changeset: 5f44d10bacca2d693413b529e0caadc73e634e1e 10:00:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:00:18 INFO - mozversion platform_version: 56.0a1 10:00:18 INFO - using testdate: 1500224418 10:00:18 INFO - actual date: 1500224418 10:00:18 INFO - starting webserver on 'localhost:54157' 10:00:18 INFO - SUITE-START | Running 2 tests 10:00:18 INFO - TEST-START | dromaeo_css 10:00:18 INFO - Initialising browser for dromaeo_css test... 10:00:18 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54157/getInfo.html -profile /tmp/tmpGtk9jx/profile 10:00:18 INFO - TEST-INFO | started process 16756 (/builds/slave/test/build/application/firefox/firefox http://localhost:54157/getInfo.html) 10:00:25 INFO - TEST-INFO | 16756: exit 0 10:00:25 INFO - Browser initialized. 10:00:25 INFO - Running cycle 1/1 for dromaeo_css test... 10:00:25 INFO - TEST-INFO | started process 16979 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGtk9jx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 10:00:36 INFO - PID 16979 | RSS: Main: 169631744 10:00:36 INFO - PID 16979 | 10:03:34 INFO - PID 16979 | Cycle 1(1): loaded http://localhost:54157/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54157/tests/dromaeo/cssquery-ext.html) 10:03:36 INFO - PID 16979 | RSS: Main: 176644096 10:03:36 INFO - PID 16979 | 10:06:37 INFO - PID 16979 | Cycle 1(1): loaded http://localhost:54157/tests/dromaeo/cssquery-ext.html (next: http://localhost:54157/tests/dromaeo/cssquery-jquery.html) 10:06:38 INFO - PID 16979 | RSS: Main: 175259648 10:06:38 INFO - PID 16979 | 10:09:40 INFO - PID 16979 | Cycle 1(1): loaded http://localhost:54157/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54157/tests/dromaeo/cssquery-mootools.html) 10:09:40 INFO - PID 16979 | RSS: Main: 177782784 10:09:40 INFO - PID 16979 | 10:12:42 INFO - PID 16979 | Cycle 1(1): loaded http://localhost:54157/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54157/tests/dromaeo/cssquery-prototype.html) 10:12:43 INFO - PID 16979 | RSS: Main: 176807936 10:12:43 INFO - PID 16979 | 10:15:46 INFO - PID 16979 | Cycle 1(1): loaded http://localhost:54157/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54157/tests/dromaeo/cssquery-yui.html) 10:15:46 INFO - PID 16979 | RSS: Main: 176115712 10:15:46 INFO - PID 16979 | 10:18:48 INFO - PID 16979 | Cycle 1(1): loaded http://localhost:54157/tests/dromaeo/cssquery-yui.html (next: http://localhost:54157/tests/dromaeo/cssquery-dojo.html) 10:18:48 INFO - PID 16979 | RSS: Main: 180211712 10:18:48 INFO - PID 16979 | 10:18:48 INFO - PID 16979 | __start_tp_report 10:18:48 INFO - PID 16979 | _x_x_mozilla_page_load 10:18:48 INFO - PID 16979 | _x_x_mozilla_page_load_details 10:18:48 INFO - PID 16979 | |i|pagename|runs| 10:18:48 INFO - PID 16979 | |0;dojo.html;2994.5657879607443;3065.1724034510685;3090.366474872653;3093.1803072185876;3097.597312349395;5873.500752436043;5969.820905372835;5983.2820061592665;6001.399860013997;6017.638941663503;6008.729607167677;6015.127806468059;6049.274087109553;6052.303985041722;6055.394460553943;5524.558035357162;5531.612787104904;5539.695316757587;5554.805581804638;5570.387257401682;5563.860903477405;5568.805091821787;5582.190582365554;5585;5586.748596313156;5533.363663178746;5547.140193270029;5567.554595632339;5585.972070139644;5592.328920529542;5534.280543529336;5547.55619550435;5556.111022236463;5560.749766260528;5571.609987300911;5386.407495175527;5496.615236933416;5513.696746678931;5513.751881165357;5579.163125531203;6005;6005.3394126646035;6043.093535969596;6049.758009679607;6073.635581865102;6235.345288744662;6373.490120790372;6375.776847810304;6424.325445828167;6435.871282574369;6336.6198028118;6345.524085693592;6402.903956440658;6410.423061924403;6434.163558737381;5954.4938680212235;6012.8496787580225;6024.216851809237;6029.698515074228;6038.879222415571;6437.195350581177;6563.835904102388;6581.539292249497;6630.270670226271;6666.933330666729;6271.560990730605;6284.811455656338;6317.368263173646;6351.491880649537;6357.650329231937;6285.59143655661;6351.5871468355335;6397.10440538325;6472.255690595548;6473.643949582818;6136.938630613726;6242.625442473466;6248.218972628421;6269.592476489013;6390.137331460193;5317.9202311965355;6303.180586523814;6303.495720342362;6316.684165791692;6508.446782023581;5100.56645185156;5163.122269214243;5166.5866730661455;5240.764165612557;5257.579393648499;4452.265376212953;4463.955360446419;4471.821127154943;4473.910521789546;4480.462344518679;5513.173024046345;5525.392206857243;5532.474414930575;5536.695481748542;5588.524975377139;6443.838904027437;6456.322086180978;6475.4819614430735;6475.805725828232;6479.708413121421;5519.558435325164;5549.084401073777;5571.721413929287;5632.295963004624;5640.097584386561;5563.471470210405;5644.435556444323;5665.433456654301;5716.371199168088;5767.913481297784;6148.723307451175;6149.2620885494025;6153.815385538352;6154.353792851775;6159.076138579159;6298.275698294755;6301.275353334242;6358.682065896594;6368.681565921778;6380.457661098766;5149.948500515096;6112.17485639433;6174.536909731698;6343.619382837044;6361.872762544631;6262.874742505033;6283.842903927439;6289.559730818982;6361.618302901656;6401.3598640135615;6488.578242414228;6509.641969691523;6553.803385898431;6560.606363618198;6571.671416429255;6167.784127555514;6205.007198848162;6216.875662486816;6221.564490485623;6223.626582405071;6142.907856382247;6149.2313460817395;6153.107799369156;6153.907691384722;6198.349173336735;6028;6088.178095957166;6103.542234332354;6108.755649774137;6110.7250173741395;6185.195924529782;6214.036824292242;6230.43926046658;6263.592866463665;6335.714892829926;6011.428914253139;6031.728572214349;6089.908651370144;6090.208272924491;6112.541559383153 10:18:48 INFO - PID 16979 | |1;ext.html;863.9395242333035;885.8892638420198;1169.4386694386694;1194.856617205935;1254.6549698832814;36743.63256367436;37530.43704344433;37940.99999999996;40350.39474407879;40530;38382;38729.22541549167;38730.8063459683;38799.41800872989;38860.805695971554;5005.799768009293;5161.458046905077;5193.091209038422;5200;5358;7082.822929426754;7183.066201393812;7188.820279493002;7221.133463984329;7223.205447400808;7176.964115179417;7251.492395532315;7530.096388433396;7732.806679833021;7736.961315193416;7641.923580764177;7650.3879689625255;7666.578338191397;7704;7734.342580880632;7488.475806693534;7596.278353556403;7625.694972201105;7625.923740762577;7651.311381975594;38566.03584910372;38694.61305386938;38894.41658375126;38908.22183556313;38918.41622375666;3539.7699149555456;3594.4248920172645;3595.5505561804775;3606.2246616977213;3613.4941108244866;30499.23751906198;30548.236294092603;30598.84700576483;30616.69383306183;30750.384992300253;4724.71651700899;4808.35087263222;4849.127157111719;4874.122657921573;4956.083124621957;181417;217328;221250;221715;222532.8873355639;49028.26457603139;49148.75425622849;49301.506984930405;49424;49595.50404495981;45638;45822.31266531005;46375.30437043447;51505.742471287405;51611.22583161256;14536.491222807152;16360.10019448918;16649.916750416414;16671;16759.2458339375;13911.79132313016;13954.651133721534;13967.720645587235;13994.440222391195;14029.859701402855;14371.353289102015;14401.207933563755;14417.42330306797;14431.278436078153;14503.85496145046;5655.971720141373;5656.745446454919;5657.858553536194;5776.855578610568;5836.4747172753805;981.7005813227034;991.9057689519628;995.7709726762885;1035.3684252605904;1037.1080870451406;22713.772862271497;22742;22767.544649107258;23014.53970920572;23286.417839554146;1106.3527836215742;1108.5676586131415;1126.8309753537035;1129.2151954391784;1135.7728454309172;11664.123042273482;11994.400279985966;11994.64016079519;12188.81716774249;12312.015038796875;235909;236413;237092;238585;238751;52883;53209;53553.1967020495;53782.462175378525;53793.1931021035;1432.2194404049817;1474.6165996840684;1500.3398504657916;1513.674559969612;1519.3770554072778;21687.45781355479;22036.449088772268;22054.88972555127;22307.219247326266;27315.59026614642;51947.740261299965;52418.73790631023;52430.73784631053;52523.73738131285;52534.73732631312;202436;204477;206879;206977;207378;31076.22309442282;31931.327897292107;31963.840180798947;32050.19874503063;35908.4613730789;38040.809795950845;38186.61813381831;38763;44767;45395.773021134686;32202;32210.83894580512;32901.17747056247;33020.83489582537;33326.83336583398;31532.842335788173;32178;32252.516212257306;32488.675113248566;32775.836120819244;33668.49497257592;35922.64077359193;36022.27955440824;36032.27935441329;36284.09289767671;31020.379592407575;31510.684893150774;31684.524732128575;31690.683093168773;31974.52038219382;22200.555988880456;22925.197618083286;22948.65577016313;23008.884955575115;23083.6537451935 10:18:48 INFO - PID 16979 | |2;jquery.html;555.4362322242922;556.3518500946398;569.1945896556375;572.5820151289557;591.1133300049925;22101.557968840614;22979.770202297972;23142.30573082806;23153.884230578864;23334.299971000877;24105;25357.366065848364;26678;26716.866415667893;26765.598516022274;6550.475961923046;6691.966540167316;6701.530892837503;6705.228898676666;6709.396154346108;6557.245916719567;6593.637349945751;6594.901076483857;6606.075149479077;6622.73509059637;4143.295639741236;5321.414644389114;5710.971445142768;6555.901661475058;6634.27023027469;6588.604683718992;6626.403623673844;6629.966850165766;6631.568948018412;6640.933590664128;6137.754489820401;6150.692465376758;6152.138700581922;6155.138280640714;6164.167837341946;29425.70574294251;29963.40073198524;30230.395392092254;30242.54636180459;30291.545626815616;1920.4238728381429;1941.3108346536917;1956.9412917612494;1958.0014192761653;1970.9507262318414;18536.073196340265;19188.904055479772;19192.520186995298;19232.326868559674;19255.51861203467;2342.906283748648;2357.1514254868234;2374.8575085494926;2394.580948334052;2411.119941221459;130952;134896;135160.64839351678;135668.32165839206;136820.6317936818;14459.204743739196;14895.404183832525;14927.552173434815;14995.17526536051;15267.083974961537;24102.87948560246;24118.87940560286;24145.15491957773;24198.879005605213;24202.15292464755;10334.276600637882;10421.322614030063;10429.895701043044;10460.63387781424;10588.841167382496;9586.568604412818;9748.9025109748;9752.317337786288;9825.950870245746;9959.05388988045;8996.325275604226;9130.81738365229;9432.151106400457;9432.245420366355;9460.90539094614;2866.6130072439955;2954.542045982876;3845.5000849889893;4185.958140418617;4311.827526898952;954.1508057828538;958.8465845464692;978.320067553058;987.4717026390847;999.9200063995008;19388;19490.512737181685;19660.410187694393;20351.084201210684;20685.68971465429;1022.1362948308667;1029.994850025745;1033;1050.7058023753361;1061.3525749292926;10915.345079295268;11055.613053543248;12973.481060757464;13828;13917.791233131511;487459;494033;494652;498883;520773;120586.39706801409;122813;122922;123907;125178;1254.7804134276648;1290.7160424706549;1304.6347022833622;1310.7968264918952;1314.172071594889;20796.792032079484;20929.476763081046;20931.26740564073;21408.143674252857;21662.45843853916;55762.72118639381;56739;56815.431845681014;58005.70997144987;58682;67795.32204678087;71026.64486677533;71547;71786;72329.63835180791;14787;15125.470608528649;15196.772048419503;15217.467388641788;15230.847691523386;18254.17856196501;23103.884480578163;23855.165069222494;23942.88028559846;23947;17407.651846963243;18825.15286812124;18965.810341896406;19117.90441047786;19207.80792192116;15936.681266374839;16020;16851.325946961984;17452.21465034102;17851.285948562432;18752.249910003447;18894.811051889305;18974;19085.618287633893;19219.711704324167;19828.405147845588;20559.8972005139;20775.37673869786;20826.895865520575;21036.632618879725;18564.721529176804;18627.161777720306;18703.438896833115;18831.90584047071;18923.71614425757 10:18:48 INFO - PID 16979 | |3;mootools.html;1348.090039223524;1433.6559225785816;1440.77667961466;1445.1256989521346;1447.529552895309;13469;14186.077904936172;14206.076605020678;14228;14263.786043209348;13491.5277965271;13898.166110033406;14069.366878490468;14090.084144530598;14094.859051409483;2567.050191429169;2602.3884387169;2609.699884513286;2624.763771260586;2625.921222363332;2607.478504299148;2664.267326485213;2668.7197844226366;2673.4653069386104;2676.344295647559;2503.7496250374998;2613.2290974162665;2656.481986012728;2675.8528280944543;2684.543627583306;2534.619807028942;2647.4705058988184;2667.906623268176;2668.6397336359632;2671.7461841125064;2566.1916496303606;2591.1449221756775;2604.739526047399;2623.160588611645;2626.1727555819857;13888.722225555432;14002.439902403892;14012.019158658897;14046.508372206923;14068.507602233974;7447.85104297911;7856.528608283521;7860.567668778215;7866.7639970800965;7870.527768333918;5096.770645320969;5247.816326428557;5296.867578310535;5330.067238233293;5343;1753.4914874686326;1786.6951632394196;1843.2258451450423;1857.8142185781448;1860.0885566072661;22901.656475152886;23125.88437057821;23242.53514929692;23247.651285230902;23277.18529851464;6275.874482510324;6341.619502829845;6405.391487808651;6461.61230326182;6474.902876456857;15782.447614333672;15800.92099539495;15805;15809.841901580838;15832.841671583137;7240.239774823568;7506.399488040944;7522.962385188039;7526.435517336222;7528.736494222785;6768.255491895888;6808.455323574201;6930.13373328334;6945.89581156283;7018.508704390643;4741.217699079613;4935.580475659537;4943.085529177114;4945.9258111128365;5023.095842748267;1415.7168566286784;1415.9433622654979;1423.544465770964;1423.5515812519022;1446.320229492137;914.7804526913626;922.4926290540176;934.2666007184464;935.4340623922565;940.6942743608355;8048.839023219502;8092.554909480035;8113.432059755761;8312.293455056388;8338.708145214889;707.1055115279175;727.1238158019573;733.1898252431093;743.4535616321999;757.47355587867;5477.890442191134;5520;5595.104783234663;5724;5768.221290125864;17931.462056138338;18875.150618222215;19403.223871045;19458;19474.8052519473;18283.72574411385;18560.350387736366;18569;18618.16218270181;18760;10479.161667066617;10630.46847657614;10794.974477424632;10892.292001020067;11110.944445277722;8202.794930126794;8362.916370836214;8523.659053637784;8565.57172141403;8627.266682331821;15585.908986370845;16239;16533.751993720343;16630.3347866084;16669.666606667557;15893.364265429253;16361.754573681166;16577.50267491977;16689.08210048459;16975.405860794814;11159.051480623913;11381.715457113307;11392.772144557228;11401.657950261506;11411.828822567502;12426.254424734545;12593.181443206162;12724.745505090032;13167.73664526685;13211.207327560378;10496.317739346918;10736.624218152327;10789;10887.020168184901;11031.72420689457;9939.204863610748;10134.746631334276;10398.480075996018;10404.68785936423;10530.473476326306;9318;9368.672096476863;9505.524723763645;9554.570044347882;9569.186619137452;7228.168760592389;7263.854722905407;7278.890816637861;7298.452616053711;7375.8156046099275;7208.855822883618;7293.197748247795;7339.229380915141;7358.11702595692;7366.484346095722 10:18:48 INFO - PID 16979 | |4;prototype.html;491.5264896977789;492.16086572394073;506.3265856410969;510.8492994566603;514.3878784246746;5946.732397042133;5968.343482216953;5996.850078748033;6076.574639775217;6135.263768347799;5718.971405142968;5939.4357536034;5967.582269241155;5973.313068997067;6041.093835924603;4893.657443978923;4897;4908.460069392364;4909.214525675892;4923.335349727795;4819.445763737162;4847.660663753539;4879.365682461275;4882.365292511986;4911.410630724318;4777.06847164803;4835.709857408549;4846.321514987904;4856.247281671329;4885.609151267908;4648.651351148677;4782.976085119552;4789.114013907403;4879.512048795127;4886.828960986384;4350;4367;4369.082492676542;4381.211381951248;4394.143142087294;5835;5861.941380586182;5863.355030946593;5932.3474417814;5965.13505541694;5551.666899986014;5557.472040156179;5580.162975553659;5616.943830561683;5652.236948012048;6394.072859435402;6453.386103781897;6466.935330646727;6471.644059576722;6506.772262970773;4760.262159365304;4771.8091276348905;4808.759562021885;4809.735464549449;4814.638902082358;6669.333066693341;6745.763898263537;6783.152105986752;6786.524943253974;6792.898106528406;4945.332380128709;4959.578435832995;4976.353074100416;4988.201887697951;4994.126027945095;4952;4970.8757281068265;4998.750062496933;5023.949760502422;5064.82273120439;5489.39616642169;5508.889822203533;5532.253145825262;5559.722013899289;5575.080111781592;6293.433590976834;6314.273858506239;6378.266499352541;6398.264199617103;6445.323241059715;1706.3686436018752;1720.4666553368245;1726.6115124096978;1733.1074496634244;1740.537139946961;1118.8433619293305;1198.4487135917575;1203.3682316783759;1203.5967950736426;1209.9576514821938;5398.8920221595345;5494.890102197934;5658.943410565924;5677.858053548612;5684.801031963944;6498.577592456475;6532.346765323525;6569.24453687832;6602.32605794654;6623.701933413008;5567.860803479864;5645.915311270334;5702;5722.341930677942;5725.1698503716725;3416.5387672663874;3461.567304086989;3512.279982603611;3520.383932811748;3523.1368314762535;6285.340039295808;6315.936840631627;6322.36776322364;6381.680915954184;6387.361263873669;6694.698738556776;6707.5304728668525;6772.036109528181;6774.356436138559;6800.489963252776;6374.036073225972;6404.647744374011;6430;6459.580127291711;6488.188976377953;6616.4045235929;6658.234303055017;6664.333566643297;6696.723114141216;6697.765578204934;6469.967650161719;6513.967430163008;6561.536886167026;6583.63789991555;6627.668616569249;6388.425041746265;6407.935920640734;6436.551775850584;6447.451966582893;6502.674866256573;4852.199387101089;4854;4858.805647774049;4865.635077369141;4871.171900776947;6556.639384833689;6579.539432239697;6639.502037347315;6682.699278532575;6713.328667133248;6382.457491113308;6411.230652321751;6436.259830119409;6451.806445806634;6462.386073323215;6412.775552855627;6434.8391290218115;6447.516436267392;6450.774222902176;6494.870102598016;6319.778807741891;6382.872342553216;6392.552521323649;6396.392342727433;6413.679316034273;5969.432903874125;6223.222097237845;6339.055536681396;6394.776182833579;6417.839554011187;6126.264848218242;6137.386261374006;6213.565050446426;6214.1921550198185;6242.968785156045 10:18:48 INFO - PID 16979 | |5;yui.html;912.0651332384309;913.8172365526896;924.778053267216;931.790347171886;941.6657086734207;17573.1213439328;18135.365262215808;18283.26866925323;18376.908115459402;18452.538686532844;16343.18284085797;16400.917995410036;16422;16731.83268167318;18833.340833070844;1034.0590063042632;1058.9939557420444;1059.750958524746;1061.4533515239657;1068.9144868410508;1068.1454836131104;1069.2782371898977;1074.0387353318774;1074.0494662223948;1074.6883403812885;1127.0307535519446;1128.136975213962;1133.5919069135105;1133.7959167349873;1134.0813940708013;1063.9680809575725;1113.3097479562682;1117.2570240789864;1118.6755840806156;1119.3899324867925;785.308928143236;800.3317230112863;800.7397595781395;814.8859159717645;815.1196707555825;17482.300707971666;17600;17632.471025869243;17638.118094095244;17684.02737849418;19955;20099.39701808948;20528.486787830276;20706.275280365266;20827.8958605206;15063.322150503254;15429.845701542954;15479.690406191925;15479.845201547952;15924.363025478968;3442.4836274559016;3474.687278144954;3485.8779942702135;3487.668671476206;3491.1097670093964;109591.45204273927;117550.41224793906;118244.4541797761;120011;121430.39284803519;15240;15813.525594232191;15825.287862046236;16161.838381616268;16172.353105875633;28253.15240542787;28658.570121448196;29088.854555727085;29093.85453072721;29642;8240.670373185125;8415.200555947175;8420.410571259952;8447.619857106445;8462.534560599102;3970.06703424695;4013.6387725104883;4072.53165885921;4077.184563087335;4086;5063.49365063498;5146.25379320004;5194.142966410575;5205.167173252262;5229.895402091937;936.091990768953;955.0974329258784;965.8647789309503;966.8791401074866;967.3132076225959;675.1594265139933;679.3274658088457;685.4310921934782;700.5096432497272;715.1311156944338;11112.44437778108;11343.829842552368;12124.939375303067;12127.818082728767;12180.390980451119;665.7902760630386;668.4184759259475;686.5811854768588;688.4664385101586;713.005357526248;5303.28405665238;5314.335708036496;5356.357237131491;5393.514583687487;5419.187121931742;101101;102771;103622;104555;107023.46488267509;85596;90552.5472372647;92093.5395323019;92311.53844230736;93187;76311.23688763153;77981;81164.18835811684;81844.59077704692;85717;12045.254657391888;12127.636170914886;12184;12302.507899684271;12562.623121306375;35065.29869402546;35078.75310081031;36151.27697445984;36423.453648194765;36936;65039.67480162569;68960.12276051623;69251;73902;74236;18739.625207496047;18746.687731858638;18940.905295473436;19321.90339048296;19672.11475483635;19593.8040619592;20733.170673172903;20865.37403877886;21068.47328816792;21247.893760531097;17929.73105403394;18455.630887382446;18547.72178417352;18634.161462733937;18920.33778817735;18168.72746908771;18944.810551894858;19198.13608387654;19274.7108793371;19650.803491964896;17372.913135434243;17383.7392439111;17515;17564.560885977953;17611.647767044844;13234.735305294033;13374.866251337362;13399.196048237138;13539.661508462368;14267.857321426653;13273.800892986807;13471.461141554228;13823.447062117804;13951.58145255644;14040.859591403956 10:18:48 INFO - PID 16979 | __end_tp_report 10:18:48 INFO - PID 16979 | __start_cc_report 10:18:48 INFO - PID 16979 | _x_x_mozilla_cycle_collect,368 10:18:48 INFO - PID 16979 | __end_cc_report 10:18:48 INFO - PID 16979 | __startTimestamp1500225528856__endTimestamp 10:18:48 INFO - PID 16979 | 10:18:48 INFO - PID 16979 | ------- Summary: start ------- 10:18:48 INFO - PID 16979 | Number of tests: 6 10:18:48 INFO - PID 16979 | 10:18:48 INFO - PID 16979 | [#0] dojo.html Cycles:165 Average:5890.04 Median:6090.21 stddev:676.74 (11.1%) stddev-sans-first:639.57 10:18:48 INFO - PID 16979 | Values: 2994.6 3065.2 3090.4 3093.2 3097.6 5873.5 5969.8 5983.3 6001.4 6017.6 6008.7 6015.1 6049.3 6052.3 6055.4 5524.6 5531.6 5539.7 5554.8 5570.4 5563.9 5568.8 5582.2 5585.0 5586.7 5533.4 5547.1 5567.6 5586.0 5592.3 5534.3 5547.6 5556.1 5560.7 5571.6 5386.4 5496.6 5513.7 5513.8 5579.2 6005.0 6005.3 6043.1 6049.8 6073.6 6235.3 6373.5 6375.8 6424.3 6435.9 6336.6 6345.5 6402.9 6410.4 6434.2 5954.5 6012.8 6024.2 6029.7 6038.9 6437.2 6563.8 6581.5 6630.3 6666.9 6271.6 6284.8 6317.4 6351.5 6357.7 6285.6 6351.6 6397.1 6472.3 6473.6 6136.9 6242.6 6248.2 6269.6 6390.1 5317.9 6303.2 6303.5 6316.7 6508.4 5100.6 5163.1 5166.6 5240.8 5257.6 4452.3 4464.0 4471.8 4473.9 4480.5 5513.2 5525.4 5532.5 5536.7 5588.5 6443.8 6456.3 6475.5 6475.8 6479.7 5519.6 5549.1 5571.7 5632.3 5640.1 5563.5 5644.4 5665.4 5716.4 5767.9 6148.7 6149.3 6153.8 6154.4 6159.1 6298.3 6301.3 6358.7 6368.7 6380.5 5149.9 6112.2 6174.5 6343.6 6361.9 6262.9 6283.8 6289.6 6361.6 6401.4 6488.6 6509.6 6553.8 6560.6 6571.7 6167.8 6205.0 6216.9 6221.6 6223.6 6142.9 6149.2 6153.1 6153.9 6198.3 6028.0 6088.2 6103.5 6108.8 6110.7 6185.2 6214.0 6230.4 6263.6 6335.7 6011.4 6031.7 6089.9 6090.2 6112.5 10:18:48 INFO - PID 16979 | 10:18:48 INFO - PID 16979 | [#1] ext.html Cycles:180 Average:38639.36 Median:19724.12 stddev:56942.25 (288.7%) stddev-sans-first:57031.34 10:18:48 INFO - PID 16979 | Values: 863.9 885.9 1169.4 1194.9 1254.7 36743.6 37530.4 37941.0 40350.4 40530.0 38382.0 38729.2 38730.8 38799.4 38860.8 5005.8 5161.5 5193.1 5200.0 5358.0 7082.8 7183.1 7188.8 7221.1 7223.2 7177.0 7251.5 7530.1 7732.8 7737.0 7641.9 7650.4 7666.6 7704.0 7734.3 7488.5 7596.3 7625.7 7625.9 7651.3 38566.0 38694.6 38894.4 38908.2 38918.4 3539.8 3594.4 3595.6 3606.2 3613.5 30499.2 30548.2 30598.8 30616.7 30750.4 4724.7 4808.4 4849.1 4874.1 4956.1 181417.0 217328.0 221250.0 221715.0 222532.9 49028.3 49148.8 49301.5 49424.0 49595.5 45638.0 45822.3 46375.3 51505.7 51611.2 14536.5 16360.1 16649.9 16671.0 16759.2 13911.8 13954.7 13967.7 13994.4 14029.9 14371.4 14401.2 14417.4 14431.3 14503.9 5656.0 5656.7 5657.9 5776.9 5836.5 981.7 991.9 995.8 1035.4 1037.1 22713.8 22742.0 22767.5 23014.5 23286.4 1106.4 1108.6 1126.8 1129.2 1135.8 11664.1 11994.4 11994.6 12188.8 12312.0 235909.0 236413.0 237092.0 238585.0 238751.0 52883.0 53209.0 53553.2 53782.5 53793.2 1432.2 1474.6 1500.3 1513.7 1519.4 21687.5 22036.4 22054.9 22307.2 27315.6 51947.7 52418.7 52430.7 52523.7 52534.7 202436.0 204477.0 206879.0 206977.0 207378.0 31076.2 31931.3 31963.8 32050.2 35908.5 38040.8 38186.6 38763.0 44767.0 45395.8 32202.0 32210.8 32901.2 33020.8 33326.8 31532.8 32178.0 32252.5 32488.7 32775.8 33668.5 35922.6 36022.3 36032.3 36284.1 31020.4 31510.7 31684.5 31690.7 31974.5 22200.6 22925.2 22948.7 23008.9 23083.7 10:18:48 INFO - PID 16979 | 10:18:48 INFO - PID 16979 | [#2] jquery.html Cycles:180 Average:35564.55 Median:23244.09 stddev:84010.54 (361.4%) stddev-sans-first:84205.09 10:18:48 INFO - PID 16979 | Values: 555.4 556.4 569.2 572.6 591.1 22101.6 22979.8 23142.3 23153.9 23334.3 24105.0 25357.4 26678.0 26716.9 26765.6 6550.5 6692.0 6701.5 6705.2 6709.4 6557.2 6593.6 6594.9 6606.1 6622.7 4143.3 5321.4 5711.0 6555.9 6634.3 6588.6 6626.4 6630.0 6631.6 6640.9 6137.8 6150.7 6152.1 6155.1 6164.2 29425.7 29963.4 30230.4 30242.5 30291.5 1920.4 1941.3 1956.9 1958.0 1971.0 18536.1 19188.9 19192.5 19232.3 19255.5 2342.9 2357.2 2374.9 2394.6 2411.1 130952.0 134896.0 135160.6 135668.3 136820.6 14459.2 14895.4 14927.6 14995.2 15267.1 24102.9 24118.9 24145.2 24198.9 24202.2 10334.3 10421.3 10429.9 10460.6 10588.8 9586.6 9748.9 9752.3 9826.0 9959.1 8996.3 9130.8 9432.2 9432.2 9460.9 2866.6 2954.5 3845.5 4186.0 4311.8 954.2 958.8 978.3 987.5 999.9 19388.0 19490.5 19660.4 20351.1 20685.7 1022.1 1030.0 1033.0 1050.7 1061.4 10915.3 11055.6 12973.5 13828.0 13917.8 487459.0 494033.0 494652.0 498883.0 520773.0 120586.4 122813.0 122922.0 123907.0 125178.0 1254.8 1290.7 1304.6 1310.8 1314.2 20796.8 20929.5 20931.3 21408.1 21662.5 55762.7 56739.0 56815.4 58005.7 58682.0 67795.3 71026.6 71547.0 71786.0 72329.6 14787.0 15125.5 15196.8 15217.5 15230.8 18254.2 23103.9 23855.2 23942.9 23947.0 17407.7 18825.2 18965.8 19117.9 19207.8 15936.7 16020.0 16851.3 17452.2 17851.3 18752.2 18894.8 18974.0 19085.6 19219.7 19828.4 20559.9 20775.4 20826.9 21036.6 18564.7 18627.2 18703.4 18831.9 18923.7 10:18:48 INFO - PID 16979 | 10:18:48 INFO - PID 16979 | [#3] mootools.html Cycles:180 Average:8664.07 Median:2606.11 stddev:5828.37 (223.6%) stddev-sans-first:5818.80 10:18:48 INFO - PID 16979 | Values: 1348.1 1433.7 1440.8 1445.1 1447.5 13469.0 14186.1 14206.1 14228.0 14263.8 13491.5 13898.2 14069.4 14090.1 14094.9 2567.1 2602.4 2609.7 2624.8 2625.9 2607.5 2664.3 2668.7 2673.5 2676.3 2503.7 2613.2 2656.5 2675.9 2684.5 2534.6 2647.5 2667.9 2668.6 2671.7 2566.2 2591.1 2604.7 2623.2 2626.2 13888.7 14002.4 14012.0 14046.5 14068.5 7447.9 7856.5 7860.6 7866.8 7870.5 5096.8 5247.8 5296.9 5330.1 5343.0 1753.5 1786.7 1843.2 1857.8 1860.1 22901.7 23125.9 23242.5 23247.7 23277.2 6275.9 6341.6 6405.4 6461.6 6474.9 15782.4 15800.9 15805.0 15809.8 15832.8 7240.2 7506.4 7523.0 7526.4 7528.7 6768.3 6808.5 6930.1 6945.9 7018.5 4741.2 4935.6 4943.1 4945.9 5023.1 1415.7 1415.9 1423.5 1423.6 1446.3 914.8 922.5 934.3 935.4 940.7 8048.8 8092.6 8113.4 8312.3 8338.7 707.1 727.1 733.2 743.5 757.5 5477.9 5520.0 5595.1 5724.0 5768.2 17931.5 18875.2 19403.2 19458.0 19474.8 18283.7 18560.4 18569.0 18618.2 18760.0 10479.2 10630.5 10795.0 10892.3 11110.9 8202.8 8362.9 8523.7 8565.6 8627.3 15585.9 16239.0 16533.8 16630.3 16669.7 15893.4 16361.8 16577.5 16689.1 16975.4 11159.1 11381.7 11392.8 11401.7 11411.8 12426.3 12593.2 12724.7 13167.7 13211.2 10496.3 10736.6 10789.0 10887.0 11031.7 9939.2 10134.7 10398.5 10404.7 10530.5 9318.0 9368.7 9505.5 9554.6 9569.2 7228.2 7263.9 7278.9 7298.5 7375.8 7208.9 7293.2 7339.2 7358.1 7366.5 10:18:48 INFO - PID 16979 | 10:18:48 INFO - PID 16979 | [#4] prototype.html Cycles:180 Average:5394.64 Median:5943.08 stddev:1516.86 (25.5%) stddev-sans-first:1475.80 10:18:48 INFO - PID 16979 | Values: 491.5 492.2 506.3 510.8 514.4 5946.7 5968.3 5996.9 6076.6 6135.3 5719.0 5939.4 5967.6 5973.3 6041.1 4893.7 4897.0 4908.5 4909.2 4923.3 4819.4 4847.7 4879.4 4882.4 4911.4 4777.1 4835.7 4846.3 4856.2 4885.6 4648.7 4783.0 4789.1 4879.5 4886.8 4350.0 4367.0 4369.1 4381.2 4394.1 5835.0 5861.9 5863.4 5932.3 5965.1 5551.7 5557.5 5580.2 5616.9 5652.2 6394.1 6453.4 6466.9 6471.6 6506.8 4760.3 4771.8 4808.8 4809.7 4814.6 6669.3 6745.8 6783.2 6786.5 6792.9 4945.3 4959.6 4976.4 4988.2 4994.1 4952.0 4970.9 4998.8 5023.9 5064.8 5489.4 5508.9 5532.3 5559.7 5575.1 6293.4 6314.3 6378.3 6398.3 6445.3 1706.4 1720.5 1726.6 1733.1 1740.5 1118.8 1198.4 1203.4 1203.6 1210.0 5398.9 5494.9 5658.9 5677.9 5684.8 6498.6 6532.3 6569.2 6602.3 6623.7 5567.9 5645.9 5702.0 5722.3 5725.2 3416.5 3461.6 3512.3 3520.4 3523.1 6285.3 6315.9 6322.4 6381.7 6387.4 6694.7 6707.5 6772.0 6774.4 6800.5 6374.0 6404.6 6430.0 6459.6 6488.2 6616.4 6658.2 6664.3 6696.7 6697.8 6470.0 6514.0 6561.5 6583.6 6627.7 6388.4 6407.9 6436.6 6447.5 6502.7 4852.2 4854.0 4858.8 4865.6 4871.2 6556.6 6579.5 6639.5 6682.7 6713.3 6382.5 6411.2 6436.3 6451.8 6462.4 6412.8 6434.8 6447.5 6450.8 6494.9 6319.8 6382.9 6392.6 6396.4 6413.7 5969.4 6223.2 6339.1 6394.8 6417.8 6126.3 6137.4 6213.6 6214.2 6243.0 10:18:48 INFO - PID 16979 | 10:18:48 INFO - PID 16979 | [#5] yui.html Cycles:180 Average:22587.12 Median:19236.42 stddev:30130.68 (156.6%) stddev-sans-first:30171.25 10:18:48 INFO - PID 16979 | Values: 912.1 913.8 924.8 931.8 941.7 17573.1 18135.4 18283.3 18376.9 18452.5 16343.2 16400.9 16422.0 16731.8 18833.3 1034.1 1059.0 1059.8 1061.5 1068.9 1068.1 1069.3 1074.0 1074.0 1074.7 1127.0 1128.1 1133.6 1133.8 1134.1 1064.0 1113.3 1117.3 1118.7 1119.4 785.3 800.3 800.7 814.9 815.1 17482.3 17600.0 17632.5 17638.1 17684.0 19955.0 20099.4 20528.5 20706.3 20827.9 15063.3 15429.8 15479.7 15479.8 15924.4 3442.5 3474.7 3485.9 3487.7 3491.1 109591.5 117550.4 118244.5 120011.0 121430.4 15240.0 15813.5 15825.3 16161.8 16172.4 28253.2 28658.6 29088.9 29093.9 29642.0 8240.7 8415.2 8420.4 8447.6 8462.5 3970.1 4013.6 4072.5 4077.2 4086.0 5063.5 5146.3 5194.1 5205.2 5229.9 936.1 955.1 965.9 966.9 967.3 675.2 679.3 685.4 700.5 715.1 11112.4 11343.8 12124.9 12127.8 12180.4 665.8 668.4 686.6 688.5 713.0 5303.3 5314.3 5356.4 5393.5 5419.2 101101.0 102771.0 103622.0 104555.0 107023.5 85596.0 90552.5 92093.5 92311.5 93187.0 76311.2 77981.0 81164.2 81844.6 85717.0 12045.3 12127.6 12184.0 12302.5 12562.6 35065.3 35078.8 36151.3 36423.5 36936.0 65039.7 68960.1 69251.0 73902.0 74236.0 18739.6 18746.7 18940.9 19321.9 19672.1 19593.8 20733.2 20865.4 21068.5 21247.9 17929.7 18455.6 18547.7 18634.2 18920.3 18168.7 18944.8 19198.1 19274.7 19650.8 17372.9 17383.7 17515.0 17564.6 17611.6 13234.7 13374.9 13399.2 13539.7 14267.9 13273.8 13471.5 13823.4 13951.6 14040.9 10:18:48 INFO - PID 16979 | -------- Summary: end -------- 10:18:48 INFO - PID 16979 | 10:18:49 INFO - TEST-INFO | 16979: exit 0 10:18:49 INFO - TEST-OK | dromaeo_css | took 1111230ms 10:18:49 INFO - TEST-START | kraken 10:18:49 INFO - Initialising browser for kraken test... 10:18:49 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54157/getInfo.html -profile /tmp/tmp8zWMg8/profile 10:18:49 INFO - TEST-INFO | started process 17410 (/builds/slave/test/build/application/firefox/firefox http://localhost:54157/getInfo.html) 10:18:56 INFO - TEST-INFO | 17410: exit 0 10:18:56 INFO - Browser initialized. 10:18:56 INFO - Running cycle 1/1 for kraken test... 10:18:56 INFO - TEST-INFO | started process 17635 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8zWMg8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 10:19:07 INFO - PID 17635 | RSS: Main: 169922560 10:19:07 INFO - PID 17635 | 10:19:10 INFO - PID 17635 | Cycle 1(1): loaded http://localhost:54157/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54157/tests/kraken/driver.html?testName=audio-beat-detection) 10:19:11 INFO - PID 17635 | RSS: Main: 176869376 10:19:11 INFO - PID 17635 | 10:19:13 INFO - PID 17635 | Cycle 1(1): loaded http://localhost:54157/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54157/tests/kraken/driver.html?testName=audio-dft) 10:19:14 INFO - PID 17635 | RSS: Main: 177152000 10:19:14 INFO - PID 17635 | 10:19:17 INFO - PID 17635 | Cycle 1(1): loaded http://localhost:54157/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54157/tests/kraken/driver.html?testName=audio-fft) 10:19:17 INFO - PID 17635 | RSS: Main: 170692608 10:19:17 INFO - PID 17635 | 10:19:19 INFO - PID 17635 | Cycle 1(1): loaded http://localhost:54157/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54157/tests/kraken/driver.html?testName=audio-oscillator) 10:19:20 INFO - PID 17635 | RSS: Main: 171765760 10:19:20 INFO - PID 17635 | 10:19:22 INFO - PID 17635 | Cycle 1(1): loaded http://localhost:54157/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54157/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:19:23 INFO - PID 17635 | RSS: Main: 171630592 10:19:23 INFO - PID 17635 | 10:19:26 INFO - PID 17635 | Cycle 1(1): loaded http://localhost:54157/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54157/tests/kraken/driver.html?testName=imaging-darkroom) 10:19:26 INFO - PID 17635 | RSS: Main: 175955968 10:19:26 INFO - PID 17635 | 10:19:30 INFO - PID 17635 | Cycle 1(1): loaded http://localhost:54157/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54157/tests/kraken/driver.html?testName=imaging-desaturate) 10:19:30 INFO - PID 17635 | RSS: Main: 176951296 10:19:30 INFO - PID 17635 | 10:19:33 INFO - PID 17635 | Cycle 1(1): loaded http://localhost:54157/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54157/tests/kraken/driver.html?testName=json-parse-financial) 10:19:34 INFO - PID 17635 | RSS: Main: 175935488 10:19:34 INFO - PID 17635 | 10:19:35 INFO - PID 17635 | Cycle 1(1): loaded http://localhost:54157/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54157/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:19:36 INFO - PID 17635 | RSS: Main: 175394816 10:19:36 INFO - PID 17635 | 10:19:38 INFO - PID 17635 | Cycle 1(1): loaded http://localhost:54157/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54157/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:19:38 INFO - PID 17635 | RSS: Main: 175366144 10:19:38 INFO - PID 17635 | 10:19:40 INFO - PID 17635 | Cycle 1(1): loaded http://localhost:54157/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54157/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:19:41 INFO - PID 17635 | RSS: Main: 175333376 10:19:41 INFO - PID 17635 | 10:19:44 INFO - PID 17635 | Cycle 1(1): loaded http://localhost:54157/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54157/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:19:45 INFO - PID 17635 | RSS: Main: 175407104 10:19:45 INFO - PID 17635 | 10:19:48 INFO - PID 17635 | Cycle 1(1): loaded http://localhost:54157/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54157/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:19:48 INFO - PID 17635 | RSS: Main: 176111616 10:19:48 INFO - PID 17635 | 10:19:50 INFO - PID 17635 | Cycle 1(1): loaded http://localhost:54157/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54157/tests/kraken/driver.html?testName=ai-astar) 10:19:50 INFO - PID 17635 | RSS: Main: 177479680 10:19:50 INFO - PID 17635 | 10:19:50 INFO - PID 17635 | __start_tp_report 10:19:50 INFO - PID 17635 | _x_x_mozilla_page_load 10:19:50 INFO - PID 17635 | _x_x_mozilla_page_load_details 10:19:50 INFO - PID 17635 | |i|pagename|runs| 10:19:50 INFO - PID 17635 | |0;ai-astar;100;108;108;107;111;108;108;108;110;110 10:19:50 INFO - PID 17635 | |1;audio-beat-detection;150;121;147;174;135;148;151;121;176;122 10:19:50 INFO - PID 17635 | |2;audio-dft;163;164;163;161;162;169;164;163;164;174 10:19:50 INFO - PID 17635 | |3;audio-fft;75;75;75;167;76;76;75;76;92;76 10:19:50 INFO - PID 17635 | |4;audio-oscillator;82;83;137;82;81;81;131;82;83;130 10:19:50 INFO - PID 17635 | |5;imaging-gaussian-blur;113;113;114;113;114;114;113;114;114;114 10:19:50 INFO - PID 17635 | |6;imaging-darkroom;150;150;151;151;152;151;151;150;150;149 10:19:50 INFO - PID 17635 | |7;imaging-desaturate;95;94;94;94;94;95;94;94;94;94 10:19:50 INFO - PID 17635 | |8;json-parse-financial;55;55;59;56;56;56;55;55;54;55 10:19:50 INFO - PID 17635 | |9;json-stringify-tinderbox;55;54;54;55;55;55;55;55;55;54 10:19:50 INFO - PID 17635 | |10;stanford-crypto-aes;78;77;79;78;77;79;79;78;81;86 10:19:50 INFO - PID 17635 | |11;stanford-crypto-ccm;127;138;131;131;129;127;143;125;125;178 10:19:50 INFO - PID 17635 | |12;stanford-crypto-pbkdf2;190;187;189;187;191;187;180;180;181;197 10:19:50 INFO - PID 17635 | |13;stanford-crypto-sha256-iterative;71;70;72;68;74;76;68;69;69;74 10:19:50 INFO - PID 17635 | __end_tp_report 10:19:50 INFO - PID 17635 | __start_cc_report 10:19:50 INFO - PID 17635 | _x_x_mozilla_cycle_collect,972 10:19:50 INFO - PID 17635 | __end_cc_report 10:19:50 INFO - PID 17635 | __startTimestamp1500225590700__endTimestamp 10:19:50 INFO - PID 17635 | 10:19:50 INFO - PID 17635 | ------- Summary: start ------- 10:19:50 INFO - PID 17635 | Number of tests: 14 10:19:50 INFO - PID 17635 | 10:19:50 INFO - PID 17635 | [#0] ai-astar Cycles:10 Average:107.80 Median:108.00 stddev:3.01 (2.8%) stddev-sans-first:1.32 10:19:50 INFO - PID 17635 | Values: 100.0 108.0 108.0 107.0 111.0 108.0 108.0 108.0 110.0 110.0 10:19:50 INFO - PID 17635 | 10:19:50 INFO - PID 17635 | [#1] audio-beat-detection Cycles:10 Average:144.50 Median:149.00 stddev:20.15 (13.5%) stddev-sans-first:21.27 10:19:50 INFO - PID 17635 | Values: 150.0 121.0 147.0 174.0 135.0 148.0 151.0 121.0 176.0 122.0 10:19:50 INFO - PID 17635 | 10:19:50 INFO - PID 17635 | [#2] audio-dft Cycles:10 Average:164.70 Median:164.00 stddev:3.89 (2.4%) stddev-sans-first:4.08 10:19:50 INFO - PID 17635 | Values: 163.0 164.0 163.0 161.0 162.0 169.0 164.0 163.0 164.0 174.0 10:19:50 INFO - PID 17635 | 10:19:50 INFO - PID 17635 | [#3] audio-fft Cycles:10 Average:86.30 Median:76.00 stddev:28.83 (37.9%) stddev-sans-first:30.29 10:19:50 INFO - PID 17635 | Values: 75.0 75.0 75.0 167.0 76.0 76.0 75.0 76.0 92.0 76.0 10:19:50 INFO - PID 17635 | 10:19:50 INFO - PID 17635 | [#4] audio-oscillator Cycles:10 Average:97.20 Median:82.00 stddev:24.55 (29.9%) stddev-sans-first:25.41 10:19:50 INFO - PID 17635 | Values: 82.0 83.0 137.0 82.0 81.0 81.0 131.0 82.0 83.0 130.0 10:19:50 INFO - PID 17635 | 10:19:50 INFO - PID 17635 | [#5] imaging-gaussian-blur Cycles:10 Average:113.60 Median:114.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 10:19:50 INFO - PID 17635 | Values: 113.0 113.0 114.0 113.0 114.0 114.0 113.0 114.0 114.0 114.0 10:19:50 INFO - PID 17635 | 10:19:50 INFO - PID 17635 | [#6] imaging-darkroom Cycles:10 Average:150.50 Median:151.00 stddev:0.85 (0.6%) stddev-sans-first:0.88 10:19:50 INFO - PID 17635 | Values: 150.0 150.0 151.0 151.0 152.0 151.0 151.0 150.0 150.0 149.0 10:19:50 INFO - PID 17635 | 10:19:50 INFO - PID 17635 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 10:19:50 INFO - PID 17635 | Values: 95.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 94.0 10:19:50 INFO - PID 17635 | 10:19:50 INFO - PID 17635 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:55.50 stddev:1.35 (2.4%) stddev-sans-first:1.41 10:19:50 INFO - PID 17635 | Values: 55.0 55.0 59.0 56.0 56.0 56.0 55.0 55.0 54.0 55.0 10:19:50 INFO - PID 17635 | 10:19:50 INFO - PID 17635 | [#9] json-stringify-tinderbox Cycles:10 Average:54.70 Median:55.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 10:19:50 INFO - PID 17635 | Values: 55.0 54.0 54.0 55.0 55.0 55.0 55.0 55.0 55.0 54.0 10:19:50 INFO - PID 17635 | 10:19:50 INFO - PID 17635 | [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:79.00 stddev:2.66 (3.4%) stddev-sans-first:2.78 10:19:50 INFO - PID 17635 | Values: 78.0 77.0 79.0 78.0 77.0 79.0 79.0 78.0 81.0 86.0 10:19:50 INFO - PID 17635 | 10:19:50 INFO - PID 17635 | [#11] stanford-crypto-ccm Cycles:10 Average:135.40 Median:131.00 stddev:16.04 (12.2%) stddev-sans-first:16.73 10:19:50 INFO - PID 17635 | Values: 127.0 138.0 131.0 131.0 129.0 127.0 143.0 125.0 125.0 178.0 10:19:50 INFO - PID 17635 | 10:19:50 INFO - PID 17635 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.90 Median:188.00 stddev:5.40 (2.9%) stddev-sans-first:5.61 10:19:50 INFO - PID 17635 | Values: 190.0 187.0 189.0 187.0 191.0 187.0 180.0 180.0 181.0 197.0 10:19:50 INFO - PID 17635 | 10:19:50 INFO - PID 17635 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:71.50 stddev:2.81 (3.9%) stddev-sans-first:2.98 10:19:50 INFO - PID 17635 | Values: 71.0 70.0 72.0 68.0 74.0 76.0 68.0 69.0 69.0 74.0 10:19:50 INFO - PID 17635 | -------- Summary: end -------- 10:19:50 INFO - PID 17635 | 10:19:51 INFO - PID 17635 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 10:19:51 INFO - TEST-INFO | 17635: exit 0 10:19:51 INFO - TEST-OK | kraken | took 62070ms 10:19:51 INFO - SUITE-END | took 1173s 10:19:51 INFO - Completed test suite (00:19:33) 10:19:51 INFO - javascript benchmark 10:19:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8381.765695204069, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2994.5657879607443, 3065.1724034510685, 3090.366474872653, 3093.1803072185876, 3097.597312349395, 5873.500752436043, 5969.820905372835, 5983.2820061592665, 6001.399860013997, 6017.638941663503, 6008.729607167677, 6015.127806468059, 6049.274087109553, 6052.303985041722, 6055.394460553943, 5524.558035357162, 5531.612787104904, 5539.695316757587, 5554.805581804638, 5570.387257401682, 5563.860903477405, 5568.805091821787, 5582.190582365554, 5585.0, 5586.748596313156, 5533.363663178746, 5547.140193270029, 5567.554595632339, 5585.972070139644, 5592.328920529542, 5534.280543529336, 5547.55619550435, 5556.111022236463, 5560.749766260528, 5571.609987300911, 5386.407495175527, 5496.615236933416, 5513.696746678931, 5513.751881165357, 5579.163125531203, 6005.0, 6005.3394126646035, 6043.093535969596, 6049.758009679607, 6073.635581865102, 6235.345288744662, 6373.490120790372, 6375.776847810304, 6424.325445828167, 6435.871282574369, 6336.6198028118, 6345.524085693592, 6402.903956440658, 6410.423061924403, 6434.163558737381, 5954.4938680212235, 6012.8496787580225, 6024.216851809237, 6029.698515074228, 6038.879222415571, 6437.195350581177, 6563.835904102388, 6581.539292249497, 6630.270670226271, 6666.933330666729, 6271.560990730605, 6284.811455656338, 6317.368263173646, 6351.491880649537, 6357.650329231937, 6285.59143655661, 6351.5871468355335, 6397.10440538325, 6472.255690595548, 6473.643949582818, 6136.938630613726, 6242.625442473466, 6248.218972628421, 6269.592476489013, 6390.137331460193, 5317.9202311965355, 6303.180586523814, 6303.495720342362, 6316.684165791692, 6508.446782023581, 5100.56645185156, 5163.122269214243, 5166.5866730661455, 5240.764165612557, 5257.579393648499, 4452.265376212953, 4463.955360446419, 4471.821127154943, 4473.910521789546, 4480.462344518679, 5513.173024046345, 5525.392206857243, 5532.474414930575, 5536.695481748542, 5588.524975377139, 6443.838904027437, 6456.322086180978, 6475.4819614430735, 6475.805725828232, 6479.708413121421, 5519.558435325164, 5549.084401073777, 5571.721413929287, 5632.295963004624, 5640.097584386561, 5563.471470210405, 5644.435556444323, 5665.433456654301, 5716.371199168088, 5767.913481297784, 6148.723307451175, 6149.2620885494025, 6153.815385538352, 6154.353792851775, 6159.076138579159, 6298.275698294755, 6301.275353334242, 6358.682065896594, 6368.681565921778, 6380.457661098766, 5149.948500515096, 6112.17485639433, 6174.536909731698, 6343.619382837044, 6361.872762544631, 6262.874742505033, 6283.842903927439, 6289.559730818982, 6361.618302901656, 6401.3598640135615, 6488.578242414228, 6509.641969691523, 6553.803385898431, 6560.606363618198, 6571.671416429255, 6167.784127555514, 6205.007198848162, 6216.875662486816, 6221.564490485623, 6223.626582405071, 6142.907856382247, 6149.2313460817395, 6153.107799369156, 6153.907691384722, 6198.349173336735, 6028.0, 6088.178095957166, 6103.542234332354, 6108.755649774137, 6110.7250173741395, 6185.195924529782, 6214.036824292242, 6230.43926046658, 6263.592866463665, 6335.714892829926, 6011.428914253139, 6031.728572214349, 6089.908651370144, 6090.208272924491, 6112.541559383153], "value": 5841.542754105244, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [863.9395242333035, 885.8892638420198, 1169.4386694386694, 1194.856617205935, 1254.6549698832814, 36743.63256367436, 37530.43704344433, 37940.99999999996, 40350.39474407879, 40530.0, 38382.0, 38729.22541549167, 38730.8063459683, 38799.41800872989, 38860.805695971554, 5005.799768009293, 5161.458046905077, 5193.091209038422, 5200.0, 5358.0, 7082.822929426754, 7183.066201393812, 7188.820279493002, 7221.133463984329, 7223.205447400808, 7176.964115179417, 7251.492395532315, 7530.096388433396, 7732.806679833021, 7736.961315193416, 7641.923580764177, 7650.3879689625255, 7666.578338191397, 7704.0, 7734.342580880632, 7488.475806693534, 7596.278353556403, 7625.694972201105, 7625.923740762577, 7651.311381975594, 38566.03584910372, 38694.61305386938, 38894.41658375126, 38908.22183556313, 38918.41622375666, 3539.7699149555456, 3594.4248920172645, 3595.5505561804775, 3606.2246616977213, 3613.4941108244866, 30499.23751906198, 30548.236294092603, 30598.84700576483, 30616.69383306183, 30750.384992300253, 4724.71651700899, 4808.35087263222, 4849.127157111719, 4874.122657921573, 4956.083124621957, 181417.0, 217328.0, 221250.0, 221715.0, 222532.8873355639, 49028.26457603139, 49148.75425622849, 49301.506984930405, 49424.0, 49595.50404495981, 45638.0, 45822.31266531005, 46375.30437043447, 51505.742471287405, 51611.22583161256, 14536.491222807152, 16360.10019448918, 16649.916750416414, 16671.0, 16759.2458339375, 13911.79132313016, 13954.651133721534, 13967.720645587235, 13994.440222391195, 14029.859701402855, 14371.353289102015, 14401.207933563755, 14417.42330306797, 14431.278436078153, 14503.85496145046, 5655.971720141373, 5656.745446454919, 5657.858553536194, 5776.855578610568, 5836.4747172753805, 981.7005813227034, 991.9057689519628, 995.7709726762885, 1035.3684252605904, 1037.1080870451406, 22713.772862271497, 22742.0, 22767.544649107258, 23014.53970920572, 23286.417839554146, 1106.3527836215742, 1108.5676586131415, 1126.8309753537035, 1129.2151954391784, 1135.7728454309172, 11664.123042273482, 11994.400279985966, 11994.64016079519, 12188.81716774249, 12312.015038796875, 235909.0, 236413.0, 237092.0, 238585.0, 238751.0, 52883.0, 53209.0, 53553.1967020495, 53782.462175378525, 53793.1931021035, 1432.2194404049817, 1474.6165996840684, 1500.3398504657916, 1513.674559969612, 1519.3770554072778, 21687.45781355479, 22036.449088772268, 22054.88972555127, 22307.219247326266, 27315.59026614642, 51947.740261299965, 52418.73790631023, 52430.73784631053, 52523.73738131285, 52534.73732631312, 202436.0, 204477.0, 206879.0, 206977.0, 207378.0, 31076.22309442282, 31931.327897292107, 31963.840180798947, 32050.19874503063, 35908.4613730789, 38040.809795950845, 38186.61813381831, 38763.0, 44767.0, 45395.773021134686, 32202.0, 32210.83894580512, 32901.17747056247, 33020.83489582537, 33326.83336583398, 31532.842335788173, 32178.0, 32252.516212257306, 32488.675113248566, 32775.836120819244, 33668.49497257592, 35922.64077359193, 36022.27955440824, 36032.27935441329, 36284.09289767671, 31020.379592407575, 31510.684893150774, 31684.524732128575, 31690.683093168773, 31974.52038219382, 22200.555988880456, 22925.197618083286, 22948.65577016313, 23008.884955575115, 23083.6537451935], "value": 17147.404624504972, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.4362322242922, 556.3518500946398, 569.1945896556375, 572.5820151289557, 591.1133300049925, 22101.557968840614, 22979.770202297972, 23142.30573082806, 23153.884230578864, 23334.299971000877, 24105.0, 25357.366065848364, 26678.0, 26716.866415667893, 26765.598516022274, 6550.475961923046, 6691.966540167316, 6701.530892837503, 6705.228898676666, 6709.396154346108, 6557.245916719567, 6593.637349945751, 6594.901076483857, 6606.075149479077, 6622.73509059637, 4143.295639741236, 5321.414644389114, 5710.971445142768, 6555.901661475058, 6634.27023027469, 6588.604683718992, 6626.403623673844, 6629.966850165766, 6631.568948018412, 6640.933590664128, 6137.754489820401, 6150.692465376758, 6152.138700581922, 6155.138280640714, 6164.167837341946, 29425.70574294251, 29963.40073198524, 30230.395392092254, 30242.54636180459, 30291.545626815616, 1920.4238728381429, 1941.3108346536917, 1956.9412917612494, 1958.0014192761653, 1970.9507262318414, 18536.073196340265, 19188.904055479772, 19192.520186995298, 19232.326868559674, 19255.51861203467, 2342.906283748648, 2357.1514254868234, 2374.8575085494926, 2394.580948334052, 2411.119941221459, 130952.0, 134896.0, 135160.64839351678, 135668.32165839206, 136820.6317936818, 14459.204743739196, 14895.404183832525, 14927.552173434815, 14995.17526536051, 15267.083974961537, 24102.87948560246, 24118.87940560286, 24145.15491957773, 24198.879005605213, 24202.15292464755, 10334.276600637882, 10421.322614030063, 10429.895701043044, 10460.63387781424, 10588.841167382496, 9586.568604412818, 9748.9025109748, 9752.317337786288, 9825.950870245746, 9959.05388988045, 8996.325275604226, 9130.81738365229, 9432.151106400457, 9432.245420366355, 9460.90539094614, 2866.6130072439955, 2954.542045982876, 3845.5000849889893, 4185.958140418617, 4311.827526898952, 954.1508057828538, 958.8465845464692, 978.320067553058, 987.4717026390847, 999.9200063995008, 19388.0, 19490.512737181685, 19660.410187694393, 20351.084201210684, 20685.68971465429, 1022.1362948308667, 1029.994850025745, 1033.0, 1050.7058023753361, 1061.3525749292926, 10915.345079295268, 11055.613053543248, 12973.481060757464, 13828.0, 13917.791233131511, 487459.0, 494033.0, 494652.0, 498883.0, 520773.0, 120586.39706801409, 122813.0, 122922.0, 123907.0, 125178.0, 1254.7804134276648, 1290.7160424706549, 1304.6347022833622, 1310.7968264918952, 1314.172071594889, 20796.792032079484, 20929.476763081046, 20931.26740564073, 21408.143674252857, 21662.45843853916, 55762.72118639381, 56739.0, 56815.431845681014, 58005.70997144987, 58682.0, 67795.32204678087, 71026.64486677533, 71547.0, 71786.0, 72329.63835180791, 14787.0, 15125.470608528649, 15196.772048419503, 15217.467388641788, 15230.847691523386, 18254.17856196501, 23103.884480578163, 23855.165069222494, 23942.88028559846, 23947.0, 17407.651846963243, 18825.15286812124, 18965.810341896406, 19117.90441047786, 19207.80792192116, 15936.681266374839, 16020.0, 16851.325946961984, 17452.21465034102, 17851.285948562432, 18752.249910003447, 18894.811051889305, 18974.0, 19085.618287633893, 19219.711704324167, 19828.405147845588, 20559.8972005139, 20775.37673869786, 20826.895865520575, 21036.632618879725, 18564.721529176804, 18627.161777720306, 18703.438896833115, 18831.90584047071, 18923.71614425757], "value": 12365.883885289026, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1348.090039223524, 1433.6559225785816, 1440.77667961466, 1445.1256989521346, 1447.529552895309, 13469.0, 14186.077904936172, 14206.076605020678, 14228.0, 14263.786043209348, 13491.5277965271, 13898.166110033406, 14069.366878490468, 14090.084144530598, 14094.859051409483, 2567.050191429169, 2602.3884387169, 2609.699884513286, 2624.763771260586, 2625.921222363332, 2607.478504299148, 2664.267326485213, 2668.7197844226366, 2673.4653069386104, 2676.344295647559, 2503.7496250374998, 2613.2290974162665, 2656.481986012728, 2675.8528280944543, 2684.543627583306, 2534.619807028942, 2647.4705058988184, 2667.906623268176, 2668.6397336359632, 2671.7461841125064, 2566.1916496303606, 2591.1449221756775, 2604.739526047399, 2623.160588611645, 2626.1727555819857, 13888.722225555432, 14002.439902403892, 14012.019158658897, 14046.508372206923, 14068.507602233974, 7447.85104297911, 7856.528608283521, 7860.567668778215, 7866.7639970800965, 7870.527768333918, 5096.770645320969, 5247.816326428557, 5296.867578310535, 5330.067238233293, 5343.0, 1753.4914874686326, 1786.6951632394196, 1843.2258451450423, 1857.8142185781448, 1860.0885566072661, 22901.656475152886, 23125.88437057821, 23242.53514929692, 23247.651285230902, 23277.18529851464, 6275.874482510324, 6341.619502829845, 6405.391487808651, 6461.61230326182, 6474.902876456857, 15782.447614333672, 15800.92099539495, 15805.0, 15809.841901580838, 15832.841671583137, 7240.239774823568, 7506.399488040944, 7522.962385188039, 7526.435517336222, 7528.736494222785, 6768.255491895888, 6808.455323574201, 6930.13373328334, 6945.89581156283, 7018.508704390643, 4741.217699079613, 4935.580475659537, 4943.085529177114, 4945.9258111128365, 5023.095842748267, 1415.7168566286784, 1415.9433622654979, 1423.544465770964, 1423.5515812519022, 1446.320229492137, 914.7804526913626, 922.4926290540176, 934.2666007184464, 935.4340623922565, 940.6942743608355, 8048.839023219502, 8092.554909480035, 8113.432059755761, 8312.293455056388, 8338.708145214889, 707.1055115279175, 727.1238158019573, 733.1898252431093, 743.4535616321999, 757.47355587867, 5477.890442191134, 5520.0, 5595.104783234663, 5724.0, 5768.221290125864, 17931.462056138338, 18875.150618222215, 19403.223871045, 19458.0, 19474.8052519473, 18283.72574411385, 18560.350387736366, 18569.0, 18618.16218270181, 18760.0, 10479.161667066617, 10630.46847657614, 10794.974477424632, 10892.292001020067, 11110.944445277722, 8202.794930126794, 8362.916370836214, 8523.659053637784, 8565.57172141403, 8627.266682331821, 15585.908986370845, 16239.0, 16533.751993720343, 16630.3347866084, 16669.666606667557, 15893.364265429253, 16361.754573681166, 16577.50267491977, 16689.08210048459, 16975.405860794814, 11159.051480623913, 11381.715457113307, 11392.772144557228, 11401.657950261506, 11411.828822567502, 12426.254424734545, 12593.181443206162, 12724.745505090032, 13167.73664526685, 13211.207327560378, 10496.317739346918, 10736.624218152327, 10789.0, 10887.020168184901, 11031.72420689457, 9939.204863610748, 10134.746631334276, 10398.480075996018, 10404.68785936423, 10530.473476326306, 9318.0, 9368.672096476863, 9505.524723763645, 9554.570044347882, 9569.186619137452, 7228.168760592389, 7263.854722905407, 7278.890816637861, 7298.452616053711, 7375.8156046099275, 7208.855822883618, 7293.197748247795, 7339.229380915141, 7358.11702595692, 7366.484346095722], "value": 6323.154003954893, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.5264896977789, 492.16086572394073, 506.3265856410969, 510.8492994566603, 514.3878784246746, 5946.732397042133, 5968.343482216953, 5996.850078748033, 6076.574639775217, 6135.263768347799, 5718.971405142968, 5939.4357536034, 5967.582269241155, 5973.313068997067, 6041.093835924603, 4893.657443978923, 4897.0, 4908.460069392364, 4909.214525675892, 4923.335349727795, 4819.445763737162, 4847.660663753539, 4879.365682461275, 4882.365292511986, 4911.410630724318, 4777.06847164803, 4835.709857408549, 4846.321514987904, 4856.247281671329, 4885.609151267908, 4648.651351148677, 4782.976085119552, 4789.114013907403, 4879.512048795127, 4886.828960986384, 4350.0, 4367.0, 4369.082492676542, 4381.211381951248, 4394.143142087294, 5835.0, 5861.941380586182, 5863.355030946593, 5932.3474417814, 5965.13505541694, 5551.666899986014, 5557.472040156179, 5580.162975553659, 5616.943830561683, 5652.236948012048, 6394.072859435402, 6453.386103781897, 6466.935330646727, 6471.644059576722, 6506.772262970773, 4760.262159365304, 4771.8091276348905, 4808.759562021885, 4809.735464549449, 4814.638902082358, 6669.333066693341, 6745.763898263537, 6783.152105986752, 6786.524943253974, 6792.898106528406, 4945.332380128709, 4959.578435832995, 4976.353074100416, 4988.201887697951, 4994.126027945095, 4952.0, 4970.8757281068265, 4998.750062496933, 5023.949760502422, 5064.82273120439, 5489.39616642169, 5508.889822203533, 5532.253145825262, 5559.722013899289, 5575.080111781592, 6293.433590976834, 6314.273858506239, 6378.266499352541, 6398.264199617103, 6445.323241059715, 1706.3686436018752, 1720.4666553368245, 1726.6115124096978, 1733.1074496634244, 1740.537139946961, 1118.8433619293305, 1198.4487135917575, 1203.3682316783759, 1203.5967950736426, 1209.9576514821938, 5398.8920221595345, 5494.890102197934, 5658.943410565924, 5677.858053548612, 5684.801031963944, 6498.577592456475, 6532.346765323525, 6569.24453687832, 6602.32605794654, 6623.701933413008, 5567.860803479864, 5645.915311270334, 5702.0, 5722.341930677942, 5725.1698503716725, 3416.5387672663874, 3461.567304086989, 3512.279982603611, 3520.383932811748, 3523.1368314762535, 6285.340039295808, 6315.936840631627, 6322.36776322364, 6381.680915954184, 6387.361263873669, 6694.698738556776, 6707.5304728668525, 6772.036109528181, 6774.356436138559, 6800.489963252776, 6374.036073225972, 6404.647744374011, 6430.0, 6459.580127291711, 6488.188976377953, 6616.4045235929, 6658.234303055017, 6664.333566643297, 6696.723114141216, 6697.765578204934, 6469.967650161719, 6513.967430163008, 6561.536886167026, 6583.63789991555, 6627.668616569249, 6388.425041746265, 6407.935920640734, 6436.551775850584, 6447.451966582893, 6502.674866256573, 4852.199387101089, 4854.0, 4858.805647774049, 4865.635077369141, 4871.171900776947, 6556.639384833689, 6579.539432239697, 6639.502037347315, 6682.699278532575, 6713.328667133248, 6382.457491113308, 6411.230652321751, 6436.259830119409, 6451.806445806634, 6462.386073323215, 6412.775552855627, 6434.8391290218115, 6447.516436267392, 6450.774222902176, 6494.870102598016, 6319.778807741891, 6382.872342553216, 6392.552521323649, 6396.392342727433, 6413.679316034273, 5969.432903874125, 6223.222097237845, 6339.055536681396, 6394.776182833579, 6417.839554011187, 6126.264848218242, 6137.386261374006, 6213.565050446426, 6214.1921550198185, 6242.968785156045], "value": 4948.3116564343545, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [912.0651332384309, 913.8172365526896, 924.778053267216, 931.790347171886, 941.6657086734207, 17573.1213439328, 18135.365262215808, 18283.26866925323, 18376.908115459402, 18452.538686532844, 16343.18284085797, 16400.917995410036, 16422.0, 16731.83268167318, 18833.340833070844, 1034.0590063042632, 1058.9939557420444, 1059.750958524746, 1061.4533515239657, 1068.9144868410508, 1068.1454836131104, 1069.2782371898977, 1074.0387353318774, 1074.0494662223948, 1074.6883403812885, 1127.0307535519446, 1128.136975213962, 1133.5919069135105, 1133.7959167349873, 1134.0813940708013, 1063.9680809575725, 1113.3097479562682, 1117.2570240789864, 1118.6755840806156, 1119.3899324867925, 785.308928143236, 800.3317230112863, 800.7397595781395, 814.8859159717645, 815.1196707555825, 17482.300707971666, 17600.0, 17632.471025869243, 17638.118094095244, 17684.02737849418, 19955.0, 20099.39701808948, 20528.486787830276, 20706.275280365266, 20827.8958605206, 15063.322150503254, 15429.845701542954, 15479.690406191925, 15479.845201547952, 15924.363025478968, 3442.4836274559016, 3474.687278144954, 3485.8779942702135, 3487.668671476206, 3491.1097670093964, 109591.45204273927, 117550.41224793906, 118244.4541797761, 120011.0, 121430.39284803519, 15240.0, 15813.525594232191, 15825.287862046236, 16161.838381616268, 16172.353105875633, 28253.15240542787, 28658.570121448196, 29088.854555727085, 29093.85453072721, 29642.0, 8240.670373185125, 8415.200555947175, 8420.410571259952, 8447.619857106445, 8462.534560599102, 3970.06703424695, 4013.6387725104883, 4072.53165885921, 4077.184563087335, 4086.0, 5063.49365063498, 5146.25379320004, 5194.142966410575, 5205.167173252262, 5229.895402091937, 936.091990768953, 955.0974329258784, 965.8647789309503, 966.8791401074866, 967.3132076225959, 675.1594265139933, 679.3274658088457, 685.4310921934782, 700.5096432497272, 715.1311156944338, 11112.44437778108, 11343.829842552368, 12124.939375303067, 12127.818082728767, 12180.390980451119, 665.7902760630386, 668.4184759259475, 686.5811854768588, 688.4664385101586, 713.005357526248, 5303.28405665238, 5314.335708036496, 5356.357237131491, 5393.514583687487, 5419.187121931742, 101101.0, 102771.0, 103622.0, 104555.0, 107023.46488267509, 85596.0, 90552.5472372647, 92093.5395323019, 92311.53844230736, 93187.0, 76311.23688763153, 77981.0, 81164.18835811684, 81844.59077704692, 85717.0, 12045.254657391888, 12127.636170914886, 12184.0, 12302.507899684271, 12562.623121306375, 35065.29869402546, 35078.75310081031, 36151.27697445984, 36423.453648194765, 36936.0, 65039.67480162569, 68960.12276051623, 69251.0, 73902.0, 74236.0, 18739.625207496047, 18746.687731858638, 18940.905295473436, 19321.90339048296, 19672.11475483635, 19593.8040619592, 20733.170673172903, 20865.37403877886, 21068.47328816792, 21247.893760531097, 17929.73105403394, 18455.630887382446, 18547.72178417352, 18634.161462733937, 18920.33778817735, 18168.72746908771, 18944.810551894858, 19198.13608387654, 19274.7108793371, 19650.803491964896, 17372.913135434243, 17383.7392439111, 17515.0, 17564.560885977953, 17611.647767044844, 13234.735305294033, 13374.866251337362, 13399.196048237138, 13539.661508462368, 14267.857321426653, 13273.800892986807, 13471.461141554228, 13823.447062117804, 13951.58145255644, 14040.859591403956], "value": 8946.275722275304, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1541.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 108.0, 108.0, 107.0, 111.0, 108.0, 108.0, 108.0, 110.0, 110.0], "value": 107.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 121.0, 147.0, 174.0, 135.0, 148.0, 151.0, 121.0, 176.0, 122.0], "value": 144.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 163.0, 161.0, 162.0, 169.0, 164.0, 163.0, 164.0, 174.0], "value": 164.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 167.0, 76.0, 76.0, 75.0, 76.0, 92.0, 76.0], "value": 86.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 137.0, 82.0, 81.0, 81.0, 131.0, 82.0, 83.0, 130.0], "value": 97.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 113.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 151.0, 151.0, 152.0, 151.0, 151.0, 150.0, 150.0, 149.0], "value": 150.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 59.0, 56.0, 56.0, 56.0, 55.0, 55.0, 54.0, 55.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 55.0, 55.0, 55.0, 55.0, 55.0, 55.0, 54.0], "value": 54.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 79.0, 78.0, 77.0, 79.0, 79.0, 78.0, 81.0, 86.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 138.0, 131.0, 131.0, 129.0, 127.0, 143.0, 125.0, 125.0, 178.0], "value": 135.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 187.0, 189.0, 187.0, 191.0, 187.0, 180.0, 180.0, 181.0, 197.0], "value": 186.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 72.0, 68.0, 74.0, 76.0, 68.0, 69.0, 69.0, 74.0], "value": 71.1, "unit": "score"}]}]} 10:19:51 INFO - Return code: 0 10:19:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:19:51 INFO - # TBPL SUCCESS # 10:19:51 INFO - Running post-action listener: _package_coverage_data 10:19:51 INFO - Running post-action listener: _resource_record_post_action 10:19:51 INFO - [mozharness: 2017-07-16 17:19:51.978888Z] Finished run-tests step (success) 10:19:51 INFO - Running post-run listener: _resource_record_post_run 10:19:52 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 434937856; Read time: 12; Write time: 372348 10:19:52 INFO - TinderboxPrint: CPU usage
13.1% 10:19:52 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 10:19:52 INFO - TinderboxPrint: I/O write bytes / time
434,937,856 / 372,348 10:19:52 INFO - TinderboxPrint: CPU idle
8,256.9 (86.9%) 10:19:52 INFO - TinderboxPrint: CPU user
1,197.0 (12.6%) 10:19:52 INFO - TinderboxPrint: Swap in / out
0 / 0 10:19:52 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 109158400; Read time: 0; Write time: 117400 10:19:52 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:19:52 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 325779456; Read time: 0; Write time: 254948 10:19:52 INFO - Running post-run listener: _upload_blobber_files 10:19:52 INFO - Blob upload gear active. 10:19:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:19:52 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:19:52 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:19:52 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:19:53 INFO - Traceback (most recent call last): 10:19:53 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 10:19:53 INFO - main() 10:19:53 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 10:19:53 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 10:19:53 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 10:19:53 INFO - return set(response.json().get('whitelist', [])) 10:19:53 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 10:19:53 INFO - return json.loads(self.text or self.content, **kwargs) 10:19:53 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/__init__.py", line 488, in loads 10:19:53 INFO - return _default_decoder.decode(s) 10:19:53 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 370, in decode 10:19:53 INFO - obj, end = self.raw_decode(s) 10:19:53 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 389, in raw_decode 10:19:53 INFO - return self.scan_once(s, idx=_w(s, idx).end()) 10:19:53 INFO - simplejson.scanner.JSONDecodeError: Expecting value: line 1 column 1 (char 0) 10:19:53 ERROR - Return code: 1 10:19:53 INFO - Setting buildbot property blobber_files to {} 10:19:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:19:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:19:53 INFO - Contents: 10:19:53 INFO - blobber_files:{} 10:19:53 INFO - Running post-run listener: copy_logs_to_upload_dir 10:19:53 INFO - Copying logs to upload dir... 10:19:53 INFO - mkdir: /builds/slave/test/build/upload/logs 10:19:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.133816 ========= master_lag: 0.78 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-07-16 10:19:53.759670) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-16 10:19:53.766058) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011210 build_url: 'https://queue.taskcluster.net/v1/task/BQEDOoXIQH6z0T86mY66Ow/artifacts/public/build/target.tar.bz2' blobber_files: '{}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-16 10:19:53.798729) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 10:19:53.799087) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1500200251.725772-357784480 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004560 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-16 10:19:53.853393) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-16 10:19:53.853809) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-16 10:19:53.854289) ========= ========= Total master_lag: 0.94 =========