builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-081 starttime: 1501716801.73 results: success (0) revision: 4c7317211990d6cf156c103a73a5b3ec41f2dd4d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-02 16:33:21.733356) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-02 16:33:21.733835) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-02 16:33:21.734166) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-02 16:33:21.751197) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 16:33:21.751675) ========= 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=483e410453545d07cca6935c000001ab-1501703203.267995-960522969 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-02 16:33:22-- 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% 37.4M=0s 2017-08-02 16:33:22 (37.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.134390 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 16:33:21.930361) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 16:33:21.930740) ========= 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=483e410453545d07cca6935c000001ab-1501703203.267995-960522969 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018431 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 16:33:21.983718) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-02 16:33:21.984057) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4c7317211990d6cf156c103a73a5b3ec41f2dd4d --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 4c7317211990d6cf156c103a73a5b3ec41f2dd4d --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=483e410453545d07cca6935c000001ab-1501703203.267995-960522969 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-02 16:33:22,622 truncating revision to first 12 chars 2017-08-02 16:33:22,622 Setting DEBUG logging. 2017-08-02 16:33:22,622 attempt 1/10 2017-08-02 16:33:22,622 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4c7317211990?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-02 16:33:24,046 unpacking tar archive at: mozilla-central-4c7317211990/testing/mozharness/ program finished with exit code 0 elapsedTime=1.720366 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-02 16:33:23.717267) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-02 16:33:23.717753) ========= script_repo_revision: 4c7317211990d6cf156c103a73a5b3ec41f2dd4d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-02 16:33:23.718202) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-02 16:33:23.718523) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-02 16:33:23.727315) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 51 secs) (at 2017-08-02 16:33:23.727644) ========= /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=483e410453545d07cca6935c000001ab-1501703203.267995-960522969 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:33:24 INFO - MultiFileLogger online at 20170802 16:33:24 in /builds/slave/test 16:33:24 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 16:33:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:33:24 INFO - {'append_to_log': False, 16:33:24 INFO - 'base_work_dir': '/builds/slave/test', 16:33:24 INFO - 'blob_upload_branch': 'Firefox', 16:33:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:33:24 INFO - 'branch': 'Firefox', 16:33:24 INFO - 'buildbot_json_path': 'buildprops.json', 16:33:24 INFO - 'code_coverage': False, 16:33:24 INFO - 'config_files': ('talos/linux_config.py',), 16:33:24 INFO - 'default_actions': ('clobber', 16:33:24 INFO - 'read-buildbot-config', 16:33:24 INFO - 'download-and-extract', 16:33:24 INFO - 'populate-webroot', 16:33:24 INFO - 'create-virtualenv', 16:33:24 INFO - 'install', 16:33:24 INFO - 'setup-mitmproxy', 16:33:24 INFO - 'run-tests'), 16:33:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:33:24 INFO - 'disable_ccov_upload': False, 16:33:24 INFO - 'download_minidump_stackwalk': True, 16:33:24 INFO - 'download_symbols': 'ondemand', 16:33:24 INFO - 'enable_stylo': False, 16:33:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:33:24 INFO - 'tooltool.py': '/tools/tooltool.py', 16:33:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:33:24 INFO - '/tools/misc-python/virtualenv.py')}, 16:33:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:33:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:33:24 INFO - 'gecko_profile': False, 16:33:24 INFO - 'gecko_profile_interval': 0, 16:33:24 INFO - 'installer_path': 'installer.exe', 16:33:24 INFO - 'log_level': 'info', 16:33:24 INFO - 'log_name': 'talos', 16:33:24 INFO - 'log_to_console': True, 16:33:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:33:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:33:24 INFO - 'opt_config_files': (), 16:33:24 INFO - 'pip_index': False, 16:33:24 INFO - 'suite': 'dromaeojs-e10s', 16:33:24 INFO - 'system_bits': '32', 16:33:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:33:24 INFO - 'title': 'talos-linux64-ix-081', 16:33:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:33:24 INFO - 'use_talos_json': True, 16:33:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:33:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:33:24 INFO - 'work_dir': 'build'} 16:33:24 INFO - [mozharness: 2017-08-02 23:33:24.474468Z] Running clobber step. 16:33:24 INFO - Running pre-action listener: _resource_record_pre_action 16:33:24 INFO - Running main action method: clobber 16:33:24 INFO - rmtree: /builds/slave/test/build 16:33:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:33:25 INFO - Running post-action listener: _resource_record_post_action 16:33:25 INFO - [mozharness: 2017-08-02 23:33:25.502268Z] Finished clobber step (success) 16:33:25 INFO - [mozharness: 2017-08-02 23:33:25.502370Z] Running read-buildbot-config step. 16:33:25 INFO - Running pre-action listener: _resource_record_pre_action 16:33:25 INFO - Running main action method: read_buildbot_config 16:33:25 INFO - Using buildbot properties: 16:33:25 INFO - { 16:33:25 INFO - "project": "", 16:33:25 INFO - "product": "firefox", 16:33:25 INFO - "who": "kwierso@gmail.com", 16:33:25 INFO - "installer_path": "public/build/target.tar.bz2", 16:33:25 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 16:33:25 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:33:25 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 16:33:25 INFO - "stage_platform": "linux64-stylosequential", 16:33:25 INFO - "basedir": "/builds/slave/test", 16:33:25 INFO - "buildnumber": 165, 16:33:25 INFO - "platform": "ubuntu64_hw_styloseq", 16:33:25 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:33:25 INFO - "slavebuilddir": "test", 16:33:25 INFO - "taskId": "GvAk5VVJQfOJzYWtV-cmRw", 16:33:25 INFO - "branch": "mozilla-central", 16:33:25 INFO - "script_repo_revision": "production", 16:33:25 INFO - "revision": "4c7317211990d6cf156c103a73a5b3ec41f2dd4d", 16:33:25 INFO - "slavename": "talos-linux64-ix-081", 16:33:25 INFO - "repo_path": "mozilla-central" 16:33:25 INFO - } 16:33:25 INFO - Finding installer, test and symbols from parent task. 16:33:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GvAk5VVJQfOJzYWtV-cmRw'}, attempt #1 16:33:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GvAk5VVJQfOJzYWtV-cmRw'}, attempt #1 16:33:26 INFO - Task dependencies: exfe1HmBRcWAzUMI2Oc6Pg 16:33:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg'}, attempt #1 16:33:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.tar.bz2 16:33:26 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.test_packages.json 16:33:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.crashreporter-symbols.zip 16:33:26 INFO - Running post-action listener: _resource_record_post_action 16:33:26 INFO - [mozharness: 2017-08-02 23:33:26.863085Z] Finished read-buildbot-config step (success) 16:33:26 INFO - [mozharness: 2017-08-02 23:33:26.863306Z] Running download-and-extract step. 16:33:26 INFO - Running pre-action listener: _resource_record_pre_action 16:33:26 INFO - Running main action method: download_and_extract 16:33:26 INFO - mkdir: /builds/slave/test/build/tests 16:33:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:33:26 INFO - trying https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.test_packages.json 16:33:26 INFO - Downloading https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:33:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:33:27 INFO - Downloaded 989 bytes. 16:33:27 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:33:27 INFO - Using the following test package requirements: 16:33:27 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:33:27 INFO - u'common': [u'target.common.tests.zip'], 16:33:27 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:33:27 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:33:27 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:33:27 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:33:27 INFO - u'mozbase': [u'target.common.tests.zip'], 16:33:27 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:33:27 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:33:27 INFO - u'web-platform': [u'target.common.tests.zip', 16:33:27 INFO - u'target.web-platform.tests.tar.gz'], 16:33:27 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:33:27 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:33:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.common.tests.zip 16:33:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:33:27 INFO - Fetch https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.common.tests.zip into memory 16:33:28 INFO - Content-Length response header: 72348219 16:33:28 INFO - Bytes received: 72348219 16:33:39 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:33:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.common.tests.zip 16:33:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:33:39 INFO - Fetch https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.common.tests.zip into memory 16:33:41 INFO - Content-Length response header: 72348219 16:33:41 INFO - Bytes received: 72348219 16:33:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.talos.tests.zip 16:33:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:33:53 INFO - Fetch https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.talos.tests.zip into memory 16:33:54 INFO - Content-Length response header: 14496464 16:33:54 INFO - Bytes received: 14496464 16:33:54 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')]} 16:33:54 INFO - trying https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.tar.bz2 16:33:54 INFO - Downloading https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:33:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:33:55 INFO - Downloaded 62191359 bytes. 16:33:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.tar.bz2 16:33:55 INFO - mkdir: /builds/slave/test/properties 16:33:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:33:55 INFO - Writing to file /builds/slave/test/properties/build_url 16:33:55 INFO - Contents: 16:33:55 INFO - build_url:https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.tar.bz2 16:33:55 INFO - Running post-action listener: _resource_record_post_action 16:33:55 INFO - Running post-action listener: set_extra_try_arguments 16:33:55 INFO - [mozharness: 2017-08-02 23:33:55.415786Z] Finished download-and-extract step (success) 16:33:55 INFO - [mozharness: 2017-08-02 23:33:55.415883Z] Running populate-webroot step. 16:33:55 INFO - Running pre-action listener: _resource_record_pre_action 16:33:55 INFO - Running main action method: populate_webroot 16:33:55 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:33:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:33:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:33:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:33:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:33:55 INFO - 'tests': ['damp', 'tps']}, 16:33:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:33:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:33:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:33:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:33:55 INFO - 'other-e10s': {'tests': ['a11yr', 16:33:55 INFO - 'ts_paint', 16:33:55 INFO - 'tpaint', 16:33:55 INFO - 'sessionrestore', 16:33:55 INFO - 'sessionrestore_many_windows', 16:33:55 INFO - 'sessionrestore_no_auto_restore', 16:33:55 INFO - 'tabpaint']}, 16:33:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:33:55 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:33:55 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:33:55 INFO - 'talos_options': ['--mitmproxy', 16:33:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:33:55 INFO - '--firstNonBlankPaint'], 16:33:55 INFO - 'tests': ['quantum_pageload_google', 16:33:55 INFO - 'quantum_pageload_youtube', 16:33:55 INFO - 'quantum_pageload_amazon', 16:33:55 INFO - 'quantum_pageload_facebook']}, 16:33:55 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:33:55 INFO - 'talos_options': ['--stylo', 16:33:55 INFO - '--mitmproxy', 16:33:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:33:55 INFO - '--firstNonBlankPaint'], 16:33:55 INFO - 'tests': ['quantum_pageload_google', 16:33:55 INFO - 'quantum_pageload_youtube', 16:33:55 INFO - 'quantum_pageload_amazon', 16:33:55 INFO - 'quantum_pageload_facebook']}, 16:33:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:33:55 INFO - 'tsvgr_opacity', 16:33:55 INFO - 'tart', 16:33:55 INFO - 'tscrollx', 16:33:55 INFO - 'cart', 16:33:55 INFO - 'tsvg_static']}, 16:33:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:33:55 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:33:55 INFO - 'talos_options': ['--stylo', 16:33:55 INFO - '--stylo-threads=1', 16:33:55 INFO - '--mitmproxy', 16:33:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:33:55 INFO - '--firstNonBlankPaint'], 16:33:55 INFO - 'tests': ['tp6_google', 16:33:55 INFO - 'tp6_youtube', 16:33:55 INFO - 'tp6_amazon', 16:33:55 INFO - 'tp6_facebook']}, 16:33:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:33:55 INFO - 'talos_options': ['--xperf_path', 16:33:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:33:55 INFO - 'tests': ['tp5n']}}} 16:33:55 INFO - Running post-action listener: _resource_record_post_action 16:33:55 INFO - [mozharness: 2017-08-02 23:33:55.424555Z] Finished populate-webroot step (success) 16:33:55 INFO - [mozharness: 2017-08-02 23:33:55.424645Z] Running create-virtualenv step. 16:33:55 INFO - Running pre-action listener: _resource_record_pre_action 16:33:55 INFO - Running main action method: create_virtualenv 16:33:55 INFO - Creating virtualenv /builds/slave/test/build/venv 16:33:55 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 16:33:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:33:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:33:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:33:55 INFO - Using real prefix '/usr' 16:33:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:33:56 INFO - Installing distribute.............................................................................................................................................................................................done. 16:33:57 INFO - Installing pip.................done. 16:33:57 INFO - Return code: 0 16:33:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:33:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:33:58 INFO - Reading from file tmpfile_stdout 16:33:58 INFO - Output received: 16:33:58 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) 16:33:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:33:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:33:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:58 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 0x188d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9eb0>, '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 0x1bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb240>, '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': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', '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 16:33:58 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 16:33:58 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 16:33:58 INFO - Using env: {'DISPLAY': ':0', 16:33:58 INFO - 'HOME': '/home/cltbld', 16:33:58 INFO - 'LANG': 'en_US.UTF-8', 16:33:58 INFO - 'LANGUAGE': 'en_US:en', 16:33:58 INFO - 'LOGNAME': 'cltbld', 16:33:58 INFO - 'MAIL': '/var/mail/cltbld', 16:33:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:58 INFO - 'MOZ_NO_REMOTE': '1', 16:33:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:33:58 INFO - 'NO_EM_RESTART': '1', 16:33:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:33:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:58 INFO - 'PWD': '/builds/slave/test', 16:33:58 INFO - 'SHELL': '/bin/bash', 16:33:58 INFO - 'SHLVL': '1', 16:33:58 INFO - 'TERM': 'linux', 16:33:58 INFO - 'TMOUT': '86400', 16:33:58 INFO - 'USER': 'cltbld', 16:33:58 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', 16:33:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:58 INFO - '_': '/tools/buildbot/bin/python'} 16:33:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:58 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 16:33:58 INFO - Cleaning up... 16:33:58 INFO - Return code: 0 16:33:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:33:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:33:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:33:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:33:58 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 0x188d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9eb0>, '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 0x1bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb240>, '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': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', '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 16:33:58 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 16:33:58 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 16:33:58 INFO - Using env: {'DISPLAY': ':0', 16:33:58 INFO - 'HOME': '/home/cltbld', 16:33:58 INFO - 'LANG': 'en_US.UTF-8', 16:33:58 INFO - 'LANGUAGE': 'en_US:en', 16:33:58 INFO - 'LOGNAME': 'cltbld', 16:33:58 INFO - 'MAIL': '/var/mail/cltbld', 16:33:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:33:58 INFO - 'MOZ_NO_REMOTE': '1', 16:33:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:33:58 INFO - 'NO_EM_RESTART': '1', 16:33:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:33:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:33:58 INFO - 'PWD': '/builds/slave/test', 16:33:58 INFO - 'SHELL': '/bin/bash', 16:33:58 INFO - 'SHLVL': '1', 16:33:58 INFO - 'TERM': 'linux', 16:33:58 INFO - 'TMOUT': '86400', 16:33:58 INFO - 'USER': 'cltbld', 16:33:58 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', 16:33:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:33:58 INFO - '_': '/tools/buildbot/bin/python'} 16:33:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:33:58 INFO - Downloading/unpacking psutil>=3.1.1 16:33:58 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 16:33:58 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 16:33:58 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 16:33:58 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 16:33:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:33:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:33:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:33:59 INFO - Installing collected packages: psutil 16:33:59 INFO - Running setup.py install for psutil 16:33:59 INFO - building 'psutil._psutil_linux' extension 16:33:59 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 16:34:00 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 16:34:00 INFO - building 'psutil._psutil_posix' extension 16:34:00 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 16:34:00 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 16:34:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:34:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:34:00 INFO - Successfully installed psutil 16:34:00 INFO - Cleaning up... 16:34:00 INFO - Return code: 0 16:34:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:00 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 0x188d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9eb0>, '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 0x1bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb240>, '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': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', '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 16:34:00 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 16:34:00 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 16:34:00 INFO - Using env: {'DISPLAY': ':0', 16:34:00 INFO - 'HOME': '/home/cltbld', 16:34:00 INFO - 'LANG': 'en_US.UTF-8', 16:34:00 INFO - 'LANGUAGE': 'en_US:en', 16:34:00 INFO - 'LOGNAME': 'cltbld', 16:34:00 INFO - 'MAIL': '/var/mail/cltbld', 16:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:00 INFO - 'MOZ_NO_REMOTE': '1', 16:34:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:00 INFO - 'NO_EM_RESTART': '1', 16:34:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:00 INFO - 'PWD': '/builds/slave/test', 16:34:00 INFO - 'SHELL': '/bin/bash', 16:34:00 INFO - 'SHLVL': '1', 16:34:00 INFO - 'TERM': 'linux', 16:34:00 INFO - 'TMOUT': '86400', 16:34:00 INFO - 'USER': 'cltbld', 16:34:00 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', 16:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:00 INFO - '_': '/tools/buildbot/bin/python'} 16:34:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:34:00 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 16:34:00 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 16:34:00 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 16:34:00 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 16:34:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:34:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:34:01 INFO - Installing collected packages: mozsystemmonitor 16:34:01 INFO - Running setup.py install for mozsystemmonitor 16:34:01 INFO - Successfully installed mozsystemmonitor 16:34:01 INFO - Cleaning up... 16:34:01 INFO - Return code: 0 16:34:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:34:01 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')]} 16:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:01 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')]} 16:34:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:01 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 0x188d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9eb0>, '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 0x1bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb240>, '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': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', '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 16:34:01 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 16:34:01 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 16:34:01 INFO - Using env: {'DISPLAY': ':0', 16:34:01 INFO - 'HOME': '/home/cltbld', 16:34:01 INFO - 'LANG': 'en_US.UTF-8', 16:34:01 INFO - 'LANGUAGE': 'en_US:en', 16:34:01 INFO - 'LOGNAME': 'cltbld', 16:34:01 INFO - 'MAIL': '/var/mail/cltbld', 16:34:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:01 INFO - 'MOZ_NO_REMOTE': '1', 16:34:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:01 INFO - 'NO_EM_RESTART': '1', 16:34:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:01 INFO - 'PWD': '/builds/slave/test', 16:34:01 INFO - 'SHELL': '/bin/bash', 16:34:01 INFO - 'SHLVL': '1', 16:34:01 INFO - 'TERM': 'linux', 16:34:01 INFO - 'TMOUT': '86400', 16:34:01 INFO - 'USER': 'cltbld', 16:34:01 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', 16:34:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:02 INFO - '_': '/tools/buildbot/bin/python'} 16:34:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:02 INFO - Downloading/unpacking jsonschema==2.5.1 16:34:02 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 16:34:02 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 16:34:02 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 16:34:02 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 16:34:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:34:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:34:03 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 16:34:03 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 16:34:03 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 16:34:03 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 16:34:03 INFO - Downloading functools32-3.2.3-2.tar.gz 16:34:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:34:03 INFO - warning: no files found matching '*.txt' 16:34:03 INFO - no previously-included directories found matching 'build' 16:34:03 INFO - no previously-included directories found matching 'dist' 16:34:03 INFO - no previously-included directories found matching '.git*' 16:34:03 INFO - Installing collected packages: jsonschema, functools32 16:34:03 INFO - Running setup.py install for functools32 16:34:03 INFO - warning: no files found matching '*.txt' 16:34:03 INFO - no previously-included directories found matching 'build' 16:34:03 INFO - no previously-included directories found matching 'dist' 16:34:03 INFO - no previously-included directories found matching '.git*' 16:34:03 INFO - Successfully installed jsonschema functools32 16:34:03 INFO - Cleaning up... 16:34:03 INFO - Return code: 0 16:34:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:34:03 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')]} 16:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:03 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')]} 16:34:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:03 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 0x188d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9eb0>, '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 0x1bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb240>, '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': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', '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 16:34:03 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 16:34:03 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 16:34:03 INFO - Using env: {'DISPLAY': ':0', 16:34:03 INFO - 'HOME': '/home/cltbld', 16:34:03 INFO - 'LANG': 'en_US.UTF-8', 16:34:03 INFO - 'LANGUAGE': 'en_US:en', 16:34:03 INFO - 'LOGNAME': 'cltbld', 16:34:03 INFO - 'MAIL': '/var/mail/cltbld', 16:34:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:03 INFO - 'MOZ_NO_REMOTE': '1', 16:34:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:03 INFO - 'NO_EM_RESTART': '1', 16:34:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:03 INFO - 'PWD': '/builds/slave/test', 16:34:03 INFO - 'SHELL': '/bin/bash', 16:34:03 INFO - 'SHLVL': '1', 16:34:03 INFO - 'TERM': 'linux', 16:34:03 INFO - 'TMOUT': '86400', 16:34:03 INFO - 'USER': 'cltbld', 16:34:03 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', 16:34:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:03 INFO - '_': '/tools/buildbot/bin/python'} 16:34:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:34:03 INFO - Cleaning up... 16:34:03 INFO - Return code: 0 16:34:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:34:03 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')]} 16:34:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:03 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')]} 16:34:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:03 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 0x188d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9eb0>, '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 0x1bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb240>, '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': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', '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 16:34:03 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 16:34:03 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 16:34:03 INFO - Using env: {'DISPLAY': ':0', 16:34:03 INFO - 'HOME': '/home/cltbld', 16:34:03 INFO - 'LANG': 'en_US.UTF-8', 16:34:03 INFO - 'LANGUAGE': 'en_US:en', 16:34:03 INFO - 'LOGNAME': 'cltbld', 16:34:03 INFO - 'MAIL': '/var/mail/cltbld', 16:34:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:03 INFO - 'MOZ_NO_REMOTE': '1', 16:34:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:03 INFO - 'NO_EM_RESTART': '1', 16:34:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:03 INFO - 'PWD': '/builds/slave/test', 16:34:03 INFO - 'SHELL': '/bin/bash', 16:34:03 INFO - 'SHLVL': '1', 16:34:03 INFO - 'TERM': 'linux', 16:34:03 INFO - 'TMOUT': '86400', 16:34:03 INFO - 'USER': 'cltbld', 16:34:03 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', 16:34:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:03 INFO - '_': '/tools/buildbot/bin/python'} 16:34:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:04 INFO - Downloading/unpacking blobuploader==1.2.4 16:34:04 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 16:34:04 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 16:34:04 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 16:34:04 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 16:34:05 INFO - Downloading blobuploader-1.2.4.tar.gz 16:34:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:34:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:34:05 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 16:34:05 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 16:34:05 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 16:34:05 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 16:34:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:34:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:34:05 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 16:34:05 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 16:34:05 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 16:34:05 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 16:34:05 INFO - Downloading docopt-0.6.1.tar.gz 16:34:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:34:05 INFO - Installing collected packages: blobuploader, requests, docopt 16:34:05 INFO - Running setup.py install for blobuploader 16:34:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:34:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:34:05 INFO - Running setup.py install for requests 16:34:06 INFO - Running setup.py install for docopt 16:34:06 INFO - Successfully installed blobuploader requests docopt 16:34:06 INFO - Cleaning up... 16:34:06 INFO - Return code: 0 16:34:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:06 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 0x188d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9eb0>, '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 0x1bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb240>, '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': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', '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 16:34:06 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 16:34:06 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 16:34:06 INFO - Using env: {'DISPLAY': ':0', 16:34:06 INFO - 'HOME': '/home/cltbld', 16:34:06 INFO - 'LANG': 'en_US.UTF-8', 16:34:06 INFO - 'LANGUAGE': 'en_US:en', 16:34:06 INFO - 'LOGNAME': 'cltbld', 16:34:06 INFO - 'MAIL': '/var/mail/cltbld', 16:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:06 INFO - 'MOZ_NO_REMOTE': '1', 16:34:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:06 INFO - 'NO_EM_RESTART': '1', 16:34:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:06 INFO - 'PWD': '/builds/slave/test', 16:34:06 INFO - 'SHELL': '/bin/bash', 16:34:06 INFO - 'SHLVL': '1', 16:34:06 INFO - 'TERM': 'linux', 16:34:06 INFO - 'TMOUT': '86400', 16:34:06 INFO - 'USER': 'cltbld', 16:34:06 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', 16:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:06 INFO - '_': '/tools/buildbot/bin/python'} 16:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:34:06 INFO - Running setup.py (path:/tmp/pip-ahO0CJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:34:06 INFO - Running setup.py (path:/tmp/pip-Lvsmrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:34:06 INFO - Running setup.py (path:/tmp/pip-pKr4as-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:34:06 INFO - Running setup.py (path:/tmp/pip-iivWqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:34:06 INFO - Running setup.py (path:/tmp/pip-Ru5eFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:34:06 INFO - Running setup.py (path:/tmp/pip-D0RsnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:34:07 INFO - Running setup.py (path:/tmp/pip-ydkZ9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:34:07 INFO - Running setup.py (path:/tmp/pip-pq6r1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:34:07 INFO - Running setup.py (path:/tmp/pip-NCbXPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:34:07 INFO - Running setup.py (path:/tmp/pip-rqgWow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:34:07 INFO - Running setup.py (path:/tmp/pip-NhoEMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:34:07 INFO - Running setup.py (path:/tmp/pip-c9SoMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:34:07 INFO - Running setup.py (path:/tmp/pip-E2m19t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:34:07 INFO - Running setup.py (path:/tmp/pip-wtpUTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:34:07 INFO - Running setup.py (path:/tmp/pip-YXgm23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:34:07 INFO - Running setup.py (path:/tmp/pip-pFVWZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:34:07 INFO - Running setup.py (path:/tmp/pip-7HCFF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:34:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:34:07 INFO - Running setup.py install for manifestparser 16:34:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:34:07 INFO - Running setup.py install for mozcrash 16:34:08 INFO - Running setup.py install for mozdebug 16:34:08 INFO - Running setup.py install for mozdevice 16:34:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:34:08 INFO - Running setup.py install for mozfile 16:34:08 INFO - Running setup.py install for mozhttpd 16:34:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:34:08 INFO - Running setup.py install for mozinfo 16:34:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:34:08 INFO - Running setup.py install for mozInstall 16:34:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:34:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:34:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:34:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:34:08 INFO - Running setup.py install for mozleak 16:34:08 INFO - Running setup.py install for mozlog 16:34:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:34:08 INFO - Running setup.py install for moznetwork 16:34:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:34:08 INFO - Running setup.py install for mozprocess 16:34:09 INFO - Running setup.py install for mozprofile 16:34:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:34:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:34:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:34:09 INFO - Running setup.py install for mozrunner 16:34:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:34:09 INFO - Running setup.py install for mozscreenshot 16:34:09 INFO - Running setup.py install for moztest 16:34:09 INFO - Running setup.py install for mozversion 16:34:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:34:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:34:09 INFO - Cleaning up... 16:34:09 INFO - Return code: 0 16:34:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:34:09 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')]} 16:34:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:09 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')]} 16:34:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:09 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 0x188d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9eb0>, '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 0x1bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb240>, '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': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', '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 16:34:09 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 16:34:09 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 16:34:09 INFO - Using env: {'DISPLAY': ':0', 16:34:09 INFO - 'HOME': '/home/cltbld', 16:34:09 INFO - 'LANG': 'en_US.UTF-8', 16:34:09 INFO - 'LANGUAGE': 'en_US:en', 16:34:09 INFO - 'LOGNAME': 'cltbld', 16:34:09 INFO - 'MAIL': '/var/mail/cltbld', 16:34:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:09 INFO - 'MOZ_NO_REMOTE': '1', 16:34:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:09 INFO - 'NO_EM_RESTART': '1', 16:34:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:09 INFO - 'PWD': '/builds/slave/test', 16:34:09 INFO - 'SHELL': '/bin/bash', 16:34:09 INFO - 'SHLVL': '1', 16:34:09 INFO - 'TERM': 'linux', 16:34:09 INFO - 'TMOUT': '86400', 16:34:09 INFO - 'USER': 'cltbld', 16:34:09 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', 16:34:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:09 INFO - '_': '/tools/buildbot/bin/python'} 16:34:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:34:09 INFO - Running setup.py (path:/tmp/pip-3NWyWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:34:09 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)) 16:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:34:09 INFO - Running setup.py (path:/tmp/pip-CLiw3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:34:09 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)) 16:34:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:34:09 INFO - Running setup.py (path:/tmp/pip-6DBC48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:34:10 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)) 16:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:34:10 INFO - Running setup.py (path:/tmp/pip-3NRF9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:34:10 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)) 16:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:34:10 INFO - Running setup.py (path:/tmp/pip-PDXXDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:34:10 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)) 16:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:34:10 INFO - Running setup.py (path:/tmp/pip-fvoEv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:34:10 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)) 16:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:34:10 INFO - Running setup.py (path:/tmp/pip-gmWngd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:34:10 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)) 16:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:34:10 INFO - Running setup.py (path:/tmp/pip-D1O0EO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:34:10 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)) 16:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:34:10 INFO - Running setup.py (path:/tmp/pip-5ouwpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:34:10 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)) 16:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:34:10 INFO - Running setup.py (path:/tmp/pip-22Gwax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:34:10 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)) 16:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:34:10 INFO - Running setup.py (path:/tmp/pip-2ZotuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:34:10 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)) 16:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:34:10 INFO - Running setup.py (path:/tmp/pip-qxOt2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:34:10 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)) 16:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:34:10 INFO - Running setup.py (path:/tmp/pip-OCOGhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:34:10 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)) 16:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:34:10 INFO - Running setup.py (path:/tmp/pip-1cTol3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:34:10 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)) 16:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:34:10 INFO - Running setup.py (path:/tmp/pip-OEvdKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:34:10 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)) 16:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:34:10 INFO - Running setup.py (path:/tmp/pip-T8Wwuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:34:10 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)) 16:34:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:34:10 INFO - Running setup.py (path:/tmp/pip-oS89bv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:34:11 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)) 16:34:11 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)) 16:34:11 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)) 16:34:11 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)) 16:34:11 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)) 16:34:11 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)) 16:34:11 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)) 16:34:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:34:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:34:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:12 INFO - Downloading blessings-1.6.tar.gz 16:34:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:34:12 INFO - Installing collected packages: blessings 16:34:12 INFO - Running setup.py install for blessings 16:34:12 INFO - Successfully installed blessings 16:34:12 INFO - Cleaning up... 16:34:12 INFO - Return code: 0 16:34:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:34:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:34:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:34:12 INFO - Reading from file tmpfile_stdout 16:34:12 INFO - Current package versions: 16:34:12 INFO - argparse == 1.2.1 16:34:12 INFO - blessings == 1.6 16:34:12 INFO - blobuploader == 1.2.4 16:34:12 INFO - docopt == 0.6.1 16:34:12 INFO - functools32 == 3.2.3-2 16:34:12 INFO - jsonschema == 2.5.1 16:34:12 INFO - manifestparser == 1.1 16:34:12 INFO - mozInstall == 1.13 16:34:12 INFO - mozcrash == 1.0 16:34:12 INFO - mozdebug == 0.1 16:34:12 INFO - mozdevice == 0.51 16:34:12 INFO - mozfile == 1.2 16:34:12 INFO - mozhttpd == 0.7 16:34:12 INFO - mozinfo == 0.10 16:34:12 INFO - mozleak == 0.1 16:34:12 INFO - mozlog == 3.5 16:34:12 INFO - moznetwork == 0.27 16:34:12 INFO - mozprocess == 0.25 16:34:12 INFO - mozprofile == 0.28 16:34:12 INFO - mozrunner == 6.13 16:34:12 INFO - mozscreenshot == 0.1 16:34:12 INFO - mozsystemmonitor == 0.3 16:34:12 INFO - moztest == 0.8 16:34:12 INFO - mozversion == 1.4 16:34:12 INFO - psutil == 3.1.1 16:34:12 INFO - requests == 1.2.3 16:34:12 INFO - wsgiref == 0.1.2 16:34:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:12 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 0x188d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9eb0>, '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 0x1bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb240>, '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': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', '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 16:34:12 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 16:34:12 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 16:34:12 INFO - Using env: {'DISPLAY': ':0', 16:34:12 INFO - 'HOME': '/home/cltbld', 16:34:12 INFO - 'LANG': 'en_US.UTF-8', 16:34:12 INFO - 'LANGUAGE': 'en_US:en', 16:34:12 INFO - 'LOGNAME': 'cltbld', 16:34:12 INFO - 'MAIL': '/var/mail/cltbld', 16:34:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:12 INFO - 'MOZ_NO_REMOTE': '1', 16:34:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:12 INFO - 'NO_EM_RESTART': '1', 16:34:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:12 INFO - 'PWD': '/builds/slave/test', 16:34:12 INFO - 'SHELL': '/bin/bash', 16:34:12 INFO - 'SHLVL': '1', 16:34:12 INFO - 'TERM': 'linux', 16:34:12 INFO - 'TMOUT': '86400', 16:34:12 INFO - 'USER': 'cltbld', 16:34:12 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', 16:34:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:12 INFO - '_': '/tools/buildbot/bin/python'} 16:34:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:12 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)) 16:34:12 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)) 16:34:12 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)) 16:34:12 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)) 16:34:12 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)) 16:34:12 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)) 16:34:12 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)) 16:34:12 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)) 16:34:12 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)) 16:34:12 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:34:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:34:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:34:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:34:13 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)) 16:34:13 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)) 16:34:13 INFO - Installing collected packages: simplejson 16:34:13 INFO - Running setup.py install for simplejson 16:34:14 INFO - building 'simplejson._speedups' extension 16:34:14 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 16:34:14 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 16:34:14 INFO - Successfully installed simplejson 16:34:14 INFO - Cleaning up... 16:34:14 INFO - Return code: 0 16:34:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:34:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:14 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 0x188d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bfcd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bf9eb0>, '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 0x1bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb240>, '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': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', '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 16:34:14 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 16:34:14 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 16:34:14 INFO - Using env: {'DISPLAY': ':0', 16:34:14 INFO - 'HOME': '/home/cltbld', 16:34:14 INFO - 'LANG': 'en_US.UTF-8', 16:34:14 INFO - 'LANGUAGE': 'en_US:en', 16:34:14 INFO - 'LOGNAME': 'cltbld', 16:34:14 INFO - 'MAIL': '/var/mail/cltbld', 16:34:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:14 INFO - 'MOZ_NO_REMOTE': '1', 16:34:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:14 INFO - 'NO_EM_RESTART': '1', 16:34:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:14 INFO - 'PWD': '/builds/slave/test', 16:34:14 INFO - 'SHELL': '/bin/bash', 16:34:14 INFO - 'SHLVL': '1', 16:34:14 INFO - 'TERM': 'linux', 16:34:14 INFO - 'TMOUT': '86400', 16:34:14 INFO - 'USER': 'cltbld', 16:34:14 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', 16:34:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:14 INFO - '_': '/tools/buildbot/bin/python'} 16:34:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:34:15 INFO - Cleaning up... 16:34:15 INFO - Return code: 0 16:34:15 INFO - Running post-action listener: _resource_record_post_action 16:34:15 INFO - Running post-action listener: _start_resource_monitoring 16:34:15 INFO - Starting resource monitoring. 16:34:15 INFO - [mozharness: 2017-08-02 23:34:15.223341Z] Finished create-virtualenv step (success) 16:34:15 INFO - [mozharness: 2017-08-02 23:34:15.223853Z] Running install step. 16:34:15 INFO - Running pre-action listener: _resource_record_pre_action 16:34:15 INFO - Running main action method: install 16:34:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:34:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:34:15 INFO - Reading from file tmpfile_stdout 16:34:15 INFO - Detecting whether we're running mozinstall >=1.0... 16:34:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:34:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:34:15 INFO - Reading from file tmpfile_stdout 16:34:15 INFO - Output received: 16:34:15 INFO - Usage: mozinstall [options] installer 16:34:15 INFO - Options: 16:34:15 INFO - -h, --help show this help message and exit 16:34:15 INFO - -d DEST, --destination=DEST 16:34:15 INFO - Directory to install application into. [default: 16:34:15 INFO - "/builds/slave/test"] 16:34:15 INFO - --app=APP Application being installed. [default: firefox] 16:34:15 INFO - mkdir: /builds/slave/test/build/application 16:34:15 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'] 16:34:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:34:33 INFO - Reading from file tmpfile_stdout 16:34:33 INFO - Output received: 16:34:33 INFO - /builds/slave/test/build/application/firefox/firefox 16:34:33 INFO - Running post-action listener: _resource_record_post_action 16:34:33 INFO - [mozharness: 2017-08-02 23:34:33.587171Z] Finished install step (success) 16:34:33 INFO - [mozharness: 2017-08-02 23:34:33.587425Z] Running setup-mitmproxy step. 16:34:33 INFO - Running pre-action listener: _resource_record_pre_action 16:34:33 INFO - Running main action method: setup_mitmproxy 16:34:33 INFO - Skipping: mitmproxy is not required 16:34:33 INFO - Running post-action listener: _resource_record_post_action 16:34:33 INFO - [mozharness: 2017-08-02 23:34:33.588556Z] Finished setup-mitmproxy step (success) 16:34:33 INFO - [mozharness: 2017-08-02 23:34:33.588776Z] Running run-tests step. 16:34:33 INFO - Running pre-action listener: _resource_record_pre_action 16:34:33 INFO - Running pre-action listener: _set_gcov_prefix 16:34:33 INFO - Running main action method: run_tests 16:34:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:34:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:34:33 INFO - Python 2.7.3 16:34:33 INFO - Return code: 0 16:34:33 INFO - grabbing minidump binary from tooltool 16:34:33 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')]} 16:34:33 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 0x1bf9eb0>, '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 0x1bfadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bfb240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:34:33 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 16:34:33 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 16:34:33 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 16:34:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:34:33 INFO - Return code: 0 16:34:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:34:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:34:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:34:33 INFO - ENV: RUST_BACKTRACE is now 1 16:34:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:34:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:34:33 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/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-081', '--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 16:34:33 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/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-081 --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 16:34:33 INFO - Using env: {'DISPLAY': ':0', 16:34:33 INFO - 'HOME': '/home/cltbld', 16:34:33 INFO - 'LANG': 'en_US.UTF-8', 16:34:33 INFO - 'LANGUAGE': 'en_US:en', 16:34:33 INFO - 'LOGNAME': 'cltbld', 16:34:33 INFO - 'MAIL': '/var/mail/cltbld', 16:34:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:34:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:34:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:33 INFO - 'MOZ_NO_REMOTE': '1', 16:34:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:34:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:33 INFO - 'NO_EM_RESTART': '1', 16:34:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:33 INFO - 'PWD': '/builds/slave/test', 16:34:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:34:33 INFO - 'RUST_BACKTRACE': '1', 16:34:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:34:33 INFO - 'SHELL': '/bin/bash', 16:34:33 INFO - 'SHLVL': '1', 16:34:33 INFO - 'STYLO_FORCE_ENABLED': '1', 16:34:33 INFO - 'TERM': 'linux', 16:34:33 INFO - 'TMOUT': '86400', 16:34:33 INFO - 'USER': 'cltbld', 16:34:33 INFO - 'XDG_SESSION_COOKIE': '483e410453545d07cca6935c000001ab-1501703203.267995-960522969', 16:34:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:33 INFO - '_': '/tools/buildbot/bin/python'} 16:34:33 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/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-081', '--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 16:34:33 INFO - mozversion application_buildid: 20170802224818 16:34:33 INFO - mozversion application_changeset: 4c7317211990d6cf156c103a73a5b3ec41f2dd4d 16:34:33 INFO - mozversion application_display_name: Nightly 16:34:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:34:33 INFO - mozversion application_name: Firefox 16:34:33 INFO - mozversion application_remotingname: firefox 16:34:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:34:33 INFO - mozversion application_vendor: Mozilla 16:34:33 INFO - mozversion application_version: 57.0a1 16:34:33 INFO - mozversion platform_buildid: 20170802224818 16:34:33 INFO - mozversion platform_changeset: 4c7317211990d6cf156c103a73a5b3ec41f2dd4d 16:34:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:34:33 INFO - mozversion platform_version: 57.0a1 16:34:33 INFO - using testdate: 1501716873 16:34:33 INFO - actual date: 1501716873 16:34:33 INFO - starting webserver on 'localhost:54249' 16:34:33 INFO - SUITE-START | Running 2 tests 16:34:33 INFO - TEST-START | dromaeo_css 16:34:33 INFO - Initialising browser for dromaeo_css test... 16:34:33 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54249/getInfo.html -profile /tmp/tmpewulQR/profile 16:34:33 INFO - TEST-INFO | started process 25499 (/builds/slave/test/build/application/firefox/firefox http://localhost:54249/getInfo.html) 16:34:40 INFO - TEST-INFO | 25499: exit 0 16:34:40 INFO - Browser initialized. 16:34:40 INFO - Running cycle 1/1 for dromaeo_css test... 16:34:40 INFO - TEST-INFO | started process 25726 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpewulQR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:34:41 INFO - PID 25726 | 16:34:41 INFO - PID 25726 | (/builds/slave/test/build/application/firefox/firefox:25781): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:34:41 INFO - PID 25726 | 16:34:51 INFO - PID 25726 | RSS: Main: 174288896 16:34:51 INFO - PID 25726 | 16:37:51 INFO - PID 25726 | Cycle 1(1): loaded http://localhost:54249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54249/tests/dromaeo/cssquery-ext.html) 16:37:52 INFO - PID 25726 | RSS: Main: 176517120 16:37:52 INFO - PID 25726 | 16:40:54 INFO - PID 25726 | Cycle 1(1): loaded http://localhost:54249/tests/dromaeo/cssquery-ext.html (next: http://localhost:54249/tests/dromaeo/cssquery-jquery.html) 16:40:55 INFO - PID 25726 | RSS: Main: 173875200 16:40:55 INFO - PID 25726 | 16:43:56 INFO - PID 25726 | Cycle 1(1): loaded http://localhost:54249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54249/tests/dromaeo/cssquery-mootools.html) 16:43:57 INFO - PID 25726 | RSS: Main: 176119808 16:43:57 INFO - PID 25726 | 16:46:59 INFO - PID 25726 | Cycle 1(1): loaded http://localhost:54249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54249/tests/dromaeo/cssquery-prototype.html) 16:46:59 INFO - PID 25726 | RSS: Main: 174567424 16:46:59 INFO - PID 25726 | 16:50:03 INFO - PID 25726 | Cycle 1(1): loaded http://localhost:54249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54249/tests/dromaeo/cssquery-yui.html) 16:50:04 INFO - PID 25726 | RSS: Main: 178196480 16:50:04 INFO - PID 25726 | 16:53:06 INFO - PID 25726 | Cycle 1(1): loaded http://localhost:54249/tests/dromaeo/cssquery-yui.html (next: http://localhost:54249/tests/dromaeo/cssquery-dojo.html) 16:53:06 INFO - PID 25726 | RSS: Main: 178794496 16:53:06 INFO - PID 25726 | 16:53:06 INFO - PID 25726 | __start_tp_report 16:53:06 INFO - PID 25726 | _x_x_mozilla_page_load 16:53:06 INFO - PID 25726 | _x_x_mozilla_page_load_details 16:53:06 INFO - PID 25726 | |i|pagename|runs| 16:53:06 INFO - PID 25726 | |0;dojo.html;3081.445339838828;3114.8442577871106;3118.750499960003;3122.0789866989235;3151.6690747471516;6059.636421814695;6084.756609735611;6166.722497487618;6195.659238741868;6206.7827626033095;6014.669193194372;6091.939080609192;6096.329403765582;6131.754729810802;6154.323024467316;5575.191597218401;5633.042382794934;5645.576581756364;5669.971650141743;5686.9146962795385;5523.502884740373;5527.751251193706;5558.388577256498;5560.888782224353;5593.496585307322;5533.418991005947;5540.63985840921;5548.9722551386985;5549.6115271930985;5557.638753481031;5532.972335138339;5536.640118392332;5538.363088244863;5540.445955404468;5541.307336582927;5456.536194423479;5513.889722205574;5517.310336207974;5527.751251193706;5543.528800052;5980.8504787380225;6023.427774361428;6023.849403764897;6033.306169790487;6048.727807248684;6450.064740612632;6464.45052170566;6473.83815404614;6481.09264702942;6482.870342593169;6592.604443733391;6592.912169492028;6606.834829129262;6685.832854178636;6700.095487109226;5997.550183736237;6128.479079278267;6171.506279497629;6183.938160618426;6205.596636218631;6718.294579069225;6719.193696756421;6730.596164230162;6737.292584278678;6768.7292508299115;6450.903236451457;6453;6472.385123413268;6486.837829054311;6489.286178520359;6472.15861937945;6577.177852768404;6579.407853293226;6604.537682362285;6609.372109649576;6431.839204019937;6486.740530378732;6502.707378167994;6535.248446428722;6548.639824809586;6446.77436289737;6518.771842985473;6525.967370163119;6542.378474044958;6578.901316480256;5219.086659834514;5308.708021058879;5323.121684922022;5335.386430560535;5337.6797392156595;4587.105514424655;4602.631789456836;4609.377734005933;4618.468876079227;4648.372469716613;5701.372848986608;5703.372629010805;5764.221830052973;5766.538676905838;5787.536997040227;6438.066480360415;6551.868962620721;6572;6575.934240657628;6666.933330666631;5640.520555752725;5643.717814109278;5644.915326270109;5655.208270842086;5663.518600918968;5713.885722285614;5726.656400615976;5741.052726300114;5751.338596061423;5800.4489573490455;6430.3891130342545;6436.356364363526;6444.065610486453;6447.806565803034;6459.870802583922;6573.967130164318;6606.768763093269;6636.369544893228;6640.90038649411;6641.070250164884;6222.284437289589;6319.178506794087;6454.806355809334;6457.160569125984;6458.741650333934;6429.38920802523;6429.485641148791;6432.678366081584;6440.29156792743;6475.222973243052;6559.868802624016;6567.671616419065;6634.635095069818;6658.201015878126;6674.032265321404;6256.092866534418;6309;6315.905261421174;6331.620102793847;6452.870942581216;6412.326705696021;6427.035944608159;6437.742490300336;6441.742330306923;6462.159919210472;6333.588316759396;6339.778107766206;6356.2054743157105;6360.173177486897;6370.840728981812;6283.465905398092;6382.170317858649;6470.191226096738;6502.252240992347;6513.7068831903625;6165.136880836776;6192.442680158807;6254.843628909313;6315.684215789284;6336.20797400325 16:53:06 INFO - PID 25726 | |1;ext.html;861.310951239009;877.8814859993901;909.1272378516621;909.58613830707;1144.1476100305274;32668.346633067355;33177.17057073571;33187.502187467144;44590.33114503277;45271;44573.55426445734;45363.54636453634;45469.7726511367;45484.317735234;45558;7668.271514206142;7740.92259077409;7891.881621775649;7939.960300198491;7940.047194336687;7738.690452381898;7821.061472623293;7836.2947334739865;7841.490303130278;7864.252895974902;7805.804854878645;7829.451938364316;7870.173631768667;7893.526388416684;7899.368050555941;7812.687492500321;7818.218178182193;7835.882461763078;7870.370370370357;7882.842343153105;7721.03487064117;7752.728654497066;7770.4560680752365;7777.066751989741;7778.8833167502535;41431.58568414338;41486.585134148874;41823.37264941028;41845;41877.371839422434;3575;3577.695895848856;3609.1157666371646;3619.2580520993133;3620.384534629093;32113.357732845212;32279.6772032279;32408.35183296321;32455.837720811243;32465.837670811496;4807.18277892759;4956;4959.231319145538;4974.303597496353;4975.62682798788;197046;198145;198337;198548;199116.00441997842;51446;51476.22785658218;52338;52385.7380713094;52393.2141017885;53994;54013.45986540163;54200.45799542033;54279;54281;14530.273486325852;16255.18724063792;16297.185140743153;16351.918240408722;16510;11732.178747487595;11822.527098915947;13687.315634218448;13819.723605527834;13919.164850109026;14986.175760333286;15001.099934003994;15163.924180379028;15167.165805880783;15285.694286114216;5632.295963004624;5647.1529270609735;5809.912851307234;5911.852203694856;5918.112283157561;1004.1665417703289;1008.0977525115128;1014.6195176808696;1019.1744686803713;1034.9172066234682;23349.7665023351;24069.63895541568;29999.400011999638;30152.24619384533;30564.235894102825;1091.7434402915308;1093.9015488606065;1094.0973696700096;1100.1254003067581;1103.426218366461;12790.61628151157;12850.614481565568;12887.613371598867;13047.543335983195;13081.41133648988;239396;239768;240733;242233;242269;57478;57532.71233643805;57541.424585754445;57793.4220657788;57916.71041644765;1461.9707605847823;1494.9177795221367;1498.820141583017;1508.7284288827898;1522.8477152284684;24007.879960600087;27938.441231175668;28066.85966570236;28867.56698649564;28923.987660431783;55048;55542.16686749621;56076.99999999837;56081;56095;201000;201773;202085;202579;204263;37456.438153427174;38262;39835.20329593334;40197.39703904502;40302.39546406865;47239.29141062818;49587.25619115788;49594.752026239636;49736;49855;35538;35909;36038.279234414644;36162.27675446529;36203.818980905984;35215;35230.295394092485;35381;35674.28651427009;35984.64015359813;39681;39694.80152599219;39899.40150897797;40031.79984100061;40130.39804402995;34349.82825085858;34789.47815782816;34814.12964675904;34983.300333992665;35575.11062223465;24708.629370559098;25153.119640812485;25415.19854062053;25860.741392586588;26150.08474703376 16:53:06 INFO - PID 25726 | |2;jquery.html;585.2041223935449;587.3509771702268;594.7977687586219;606.5753972219447;610.7068607068607;23032.88483557582;23846.642300365485;23872.283831485063;23905.880470597665;23936.8803155984;18675.906620466914;19951.301704440346;22094.779052209473;24175.516489670197;24356.512869742637;6544.869102617945;6711.765088221913;6711.798646040626;6721.966390168042;6723.899141512881;5876.265466816648;6540.4440622547145;6570.605763654172;6598.208215014205;6626.7017984190825;6364.299927008025;6368.745250189987;6374.74501019961;6380.106785050097;6409.455196308296;6316.936830631681;6360.809175724736;6365.427111559959;6378.362163783631;6381.457576106037;5842.824715258549;5869.2369991901205;5898.734556944905;5904.409559044104;5910.231669882931;29581.260468488246;30444.8477757612;30756.84621576878;30875.845620771976;30939.84530077335;1869.457857221404;1904.4096330137563;1923.3556758485784;1924.6824273994773;1926.6820974539185;19447.3193438229;20080.29718959844;20104.899475502676;20120.29578964729;20128.79871201284;2304.5506126305377;2346.21401830387;2355.36405170603;2358.469344397514;2459.446624509489;125543.372283138;132165;132634;132891;138497;15083.54749357521;15388.53834384948;15563.455279065178;15730.056196628238;16357.263923123488;24514;24540.631890521658;24563.508729825662;24608.630870536956;24651.3837154069;9999.800003999879;10027.147692446079;10134.797304053878;10290.948545257375;10338;9341;9420.670276540426;9457.243420526342;9459.722548084794;9540.904590954002;9161.633534658673;9454.527273636428;9578.185854202331;9642.228621710387;9834.459104749307;2713.1182365731215;2726.4955983142786;2754.5868119781794;2755.6301580640634;2785.1226863537922;924.7641851327868;962.3648392061345;972.6644307713827;988.9109980101825;995.1988649137369;19427.708584371245;19607.705884411746;20116.497087572927;23659.645105323434;23870.554150287764;1011.7824667696333;1062.914966802654;1064.7178497698117;1066.664000839733;1079.5142186016167;11552.480138393792;11575.073994080454;11586.76826463466;11760;11760.76478470443;500312;502136;511479;511670;512355;115225;120466;122051;122552.38723806503;124342;1113.7494063835572;1189.5658084799159;1206.740550781586;1240;1305.3473263368505;21184.894075529523;21227.575448490636;21447.8927605361;21508.677369839777;21556.461088472915;57822.7108864453;59080;59251.70374148102;59581.40418595759;60681;68480.73467758115;68875;69287;69486.65256673684;70098.64950675418;14577;14865.182414967281;15016;15034.398624054915;15035.097894126387;24125.155619553232;24220.87889560541;24268.39329016718;24718.13486527963;24740.505189896463;18711;19290.7106393407;19479.318223862098;19866;19911.502212444804;17453.563660908072;17585.736213956545;17872.28510859551;18008.279668813102;18045.548861278574;19467.61064778725;20046.699299510812;20092.995350232723;20245.898770506643;20259.594808103462;14965.401383944956;14999.550013499613;15077.54767356981;15102.093874367572;15124.168170750714;18073;18653.44039678812;18667.439976800717;18678.71981920245;18701.06494675288 16:53:06 INFO - PID 25726 | |3;mootools.html;1366.9863301366986;1385.8544852790458;1396.692727599928;1414.271650100198;1422.1396055386485;13593.864061359396;14170.787438188423;14176.149431034146;14179.078359906598;14179.78730319046;13852.722945541083;14620.634484137901;14735.778963315532;14777;14786.334614942327;2698.190542837151;2769.8892044318204;2772.279207406069;2772.487089888367;2773.292810333362;2594.9481010379877;2620.882060307291;2664.4937461882278;2757.0212574536054;2757.2555410039276;2746.3408781892304;2772.279207406079;2773.5285001549787;2774.3618967637453;2774.8612569371553;2568.5633442314756;2611.791056715458;2616.0582190411437;2629.8027647926388;2651.562492188796;2597.298729343067;2629.1586692258484;2653.230563136688;2701.149138021517;2705.675318961737;13879.4448222072;13987.090839095528;13988.72022559543;14004.159750415009;14025.509107181302;7388.3719883809945;7618.085829700472;7618.695252189906;7640.503367281109;7648.388128949727;4901.828436004723;5072.061668591323;5175.042617115846;5232.1628539433505;5233.371995360543;1806.719958406449;1818.5544541587396;1826.56162520994;1888.2541396148445;1895.895725735084;23129.88435057814;23362.883185584134;23367.76632233673;23374.64938025931;23457.7654223459;6538.738450461929;6544.247411547638;6561.770338038241;6808.11494505721;6846.315368463114;15815.446459373867;15835.920820395824;15857.048577085412;15858.365665373256;15876.126813025394;7851.764447066597;8168;8297.377696672773;8297.543635100006;8302.169783021649;6845.212800527904;6894.413974812097;6896.586204827727;6998.650067496605;7383.6677349519405;4915.213565829522;5086.4659210783075;5118.87202819925;5133.563647089964;5162.948370516322;1469.2800527741545;1470.5661829760247;1491.7687758397465;1497.3262032085604;1499.4601943300404;908.6592527802075;950.3442624589012;955.1069749783974;956.1872408452759;958.3483231402713;7566.508176968479;7578;7606.315431611181;7630.8092297693;7742.806429839186;726.284609659485;745.1542499263267;749.636426333228;750.7935317787652;759.8556274307864;4991.650584459126;4994.1759609663995;5059.367579052619;5125.103106956268;5320.76056577455;18113.637727245383;18508.44474665762;19078;19081.80918190828;19107.522311942033;18792.154353054146;18841;18859;18984.240630374905;19205.519862003282;9625.219334423155;10323.793524129474;10641;10644;10660.573577056832;8177.713780017671;8191.303739182236;8194.549299788569;8207.097219305992;8241.134680858664;16569.502914912573;16570.66858662796;16729.91635041817;16843;17443.56391090233;16884.57788555296;17175.227114780115;17192.398266060627;17210.13949302505;17266.568335791704;10813.51339189754;10991.340519568852;11047.72380690489;11065.170112241774;11065.557377704801;12863.101556309439;12957.740845182854;12968.740625187633;13048.086633935913;13119.803202951773;10471.109955653856;10548;10584.523696433525;10620.20348473852;10750.677479675622;10362.948185259025;10396.58413663445;10431.16550675929;10444.477776111013;10714.946425267824;9243.491607961627;9439.528023598656;9466.810663786824;9475;9481.715548533555;6930.306969303231;6986.406155476638;7048.894266586108;7371.115466143882;7436.479446438698;7140.857182856417;7217.6752046157;7218.89171662436;7248.673809678472;7436.888446673413 16:53:06 INFO - PID 25726 | |4;prototype.html;496.3175633503932;502.8767951851797;513.5121634447275;526.3972751199876;531.9441458646842;6286.748530058792;6290.4024117708805;6292.779752708656;6332.651704156269;6376.394242546957;5942.554308426864;6163.907541386871;6173.32093469718;6256.562040657144;6260.718267677954;5190;5206.193040078784;5209.583233341343;5215.765290561915;5248.973755131219;5153.1754919212935;5178.608011752308;5210.218467229908;5234.6859188448625;5239.895202095956;4972.950270497285;5108.361454818148;5140.1518749406305;5157.871053223681;5230.450802665722;5094.828189516432;5136.534463457561;5143.202803565453;5147.716875571842;5186.792528298901;4786.52134786522;4805.6155507559315;4825.396825396825;4832.589229915461;4846.369971903665;5979.790707325266;6020.879582408371;6021.969890150565;6049.516038716892;6068.753217028869;5973.790917317917;6044.516438684895;6044.96977515114;6066.393360663942;6071.907056729787;6587.835304117388;6598.93401065988;6611.347163209198;6701.128853249095;6723.697433615499;5270.49930256625;5294;5316.627836051478;5336.226247194135;5372.785088596452;6953.617551034691;7018.649067546602;7075.363217310467;7080.964595177094;7092.609906455143;5495.422980587061;5524.8342549723575;5561.6663000220115;5582.385937546866;5585.804496842672;5551.916721249185;5568.972155139199;5582.972085139549;5606.794539174097;5646.661200327993;6050.27567143362;6051.8487037823525;6052.334243233241;6060.121282414026;6077.1491991121275;6692;6712;6716.227633822075;6738.157730283715;6768.172593399754;1853.731208974691;1898.8860668359666;1917.8561607879185;1963.783983761785;1969.3304276545757;1375.7592421326428;1436.6480212347833;1454.120257244352;1459.7372472954758;1482.0885155629426;5729.9713501432225;5760.135979603009;5773.422657734193;5783.739731712083;5791.62354446968;6654.334566543307;6666.933330666631;6693.665316734144;6695.330466953363;6730.259671436138;5664.433556644385;5779.797707080232;5797.652140871562;5829.795957141479;5873.471387575139;3601.891943241707;3634.5638523377365;3639.5086663300644;3647.908802279911;3653.9269214616093;6364.586301890362;6414.5189110815945;6424.1327420798525;6504.739810407626;6544.887369147256;6847.897281540781;6883.65581720912;6929.787287224715;6940.687669054904;6952.443804495628;6545.247296560957;6591.747567962072;6598.571092878948;6609.272979972296;6641.136652235178;6732.562383445061;6737.86524269502;6743.629100399525;6760.459163267025;6783.321667833177;6508.219013718384;6518.217813862367;6622.470202383702;6693.531452798257;6697.866042679022;6435.163428754232;6564.245111812203;6582.144321238208;6618.172728408949;6694.397504224643;4972.154733695208;5066.59467242627;5085.516875896932;5089.589974717022;5091.745412729423;6706.932930670826;6784;6784.59292442455;6828.692708828214;6858.525417035361;6537.182852143482;6550.934490655032;6571.211454625475;6584.806368910979;6619.657073178207;6398.200224971879;6514.348565143638;6530.106269181884;6538;6548.901766473411;6247.593906396251;6425.646589437626;6459.224893012869;6549.7380104795275;6557.311482294291;6421.903671445026;6521.043686893818;6575.605463672195;6597;6624.966875165594;6347.857385670623;6391.069492262132;6402.583832050902;6410.070539771614;6442.549021568445 16:53:06 INFO - PID 25726 | |5;yui.html;905.2079430498314;910.053544313914;927.5594092805923;934.7476181431014;935.4293880732754;18102.185401656923;18955.052247387626;18978.62042759144;19037.42887713371;20424.183032678677;20941.685874711893;21702.457438564044;21793.891030544866;21827.126914923425;21901;1016.8373060310371;1040.2614143957799;1040.7658276887678;1043.0247718383298;1044.8067107585084;1042.8800687920873;1042.9009244121796;1043.3896170740127;1045.3884477580625;1046.0271947089204;985.3939827006411;1050.8213603687354;1107.0258172807958;1107.5293000474805;1108.794872948503;1095.6932059023447;1098.2696506822992;1101.7025403141147;1101.983470247947;1103.3876198709713;789.2147313423123;790.8892755014302;791.1060501633175;794.6463823598501;796.665400531778;18443.723344149716;18561.814381856144;18735.906320468446;18798.812011879978;20047.599048018958;20579.279725209697;20676.48308792277;21039.47401314964;21211.575768484545;21218.25736099229;14528.636784080376;15660.686786264325;15732.44936427231;15838.841611583852;15847.92076039624;3544.6987006104514;3563.750537462379;3579.319929213441;3585.659362360572;3586.211033572636;118950;120516;122184;124087;126698.36650816687;15622.453214137679;16269.349226031063;16411.83588164127;16585.25366358517;16608.83391166097;29700.257493562833;29717.702822971925;30335;30415.695843041285;30467.390652186834;8376.204260595234;8530.10433904431;8557.48655080697;8567.357448191286;8596.8710469343;4019.8593049243136;4090.1206240658403;4098.672106231495;4099.85650502231;4151.896202594899;5236.816711415082;5295.761690723926;5298.311219541512;5352.116900711417;5357.249985002103;994.3288280410694;1018.2414101494434;1028.7325295423243;1033.6330602635956;1034.6740776655329;640.7757284950324;652.4584594786317;657.7270432770442;660.4452260101538;666.3835951744617;10606.204534659932;10954.616588419367;11069.948354906272;11203.271787333797;11780.293182408912;670.3196255800367;670.7596627264959;675.7398401615316;685.9451243900476;686.9969844027776;4571.771411429415;4622.607078398374;4631.305304204397;4632;4653.278741795028;98267.52598711025;101318;101514;105416;105749;88041.55979220191;90751;90945;91156;93956.53021734848;78510.6074469624;80879.59560202279;81235.59382203051;82318.58840705878;82794;8859.3798434109;8911.331650126396;8972.820543589221;9009.594568244314;9089.409188402733;35215.64784352226;35317;35389;35562.64437355593;36634;44499.4227280216;44703.10593788041;45375.092498150516;46135.53864461312;46164.76917615525;13788.103773254707;13890.86109138936;13976.720465590428;14088.15471071739;14114.223717695626;15930.761038584198;15936.28286727077;16002.119883406524;16020.11889346097;16227.350905964102;14337.211453370164;14398.640033999234;14493.275336232935;14510.564683059103;14597.27013649292;14486;14638.194899280641;14661.92669036648;14783.926080369529;14790.852091478948;17177.914110429367;17563.736543952105;18187.363442279457;18202.45392638223;18362;13630.318484075955;13679.726405472034;13790.793138103138;13945.651358715708;13953.093048951785;13707.931460342634;13803.2408217549;13842.792358114837;14061.789073163705;14098.36557354942 16:53:06 INFO - PID 25726 | __end_tp_report 16:53:06 INFO - PID 25726 | __start_cc_report 16:53:06 INFO - PID 25726 | _x_x_mozilla_cycle_collect,367 16:53:06 INFO - PID 25726 | __end_cc_report 16:53:06 INFO - PID 25726 | __startTimestamp1501717986468__endTimestamp 16:53:06 INFO - PID 25726 | 16:53:06 INFO - PID 25726 | ------- Summary: start ------- 16:53:06 INFO - PID 25726 | Number of tests: 6 16:53:06 INFO - PID 25726 | 16:53:06 INFO - PID 25726 | [#0] dojo.html Cycles:165 Average:6034.63 Median:6315.68 stddev:710.41 (11.2%) stddev-sans-first:673.75 16:53:06 INFO - PID 25726 | Values: 3081.4 3114.8 3118.8 3122.1 3151.7 6059.6 6084.8 6166.7 6195.7 6206.8 6014.7 6091.9 6096.3 6131.8 6154.3 5575.2 5633.0 5645.6 5670.0 5686.9 5523.5 5527.8 5558.4 5560.9 5593.5 5533.4 5540.6 5549.0 5549.6 5557.6 5533.0 5536.6 5538.4 5540.4 5541.3 5456.5 5513.9 5517.3 5527.8 5543.5 5980.9 6023.4 6023.8 6033.3 6048.7 6450.1 6464.5 6473.8 6481.1 6482.9 6592.6 6592.9 6606.8 6685.8 6700.1 5997.6 6128.5 6171.5 6183.9 6205.6 6718.3 6719.2 6730.6 6737.3 6768.7 6450.9 6453.0 6472.4 6486.8 6489.3 6472.2 6577.2 6579.4 6604.5 6609.4 6431.8 6486.7 6502.7 6535.2 6548.6 6446.8 6518.8 6526.0 6542.4 6578.9 5219.1 5308.7 5323.1 5335.4 5337.7 4587.1 4602.6 4609.4 4618.5 4648.4 5701.4 5703.4 5764.2 5766.5 5787.5 6438.1 6551.9 6572.0 6575.9 6666.9 5640.5 5643.7 5644.9 5655.2 5663.5 5713.9 5726.7 5741.1 5751.3 5800.4 6430.4 6436.4 6444.1 6447.8 6459.9 6574.0 6606.8 6636.4 6640.9 6641.1 6222.3 6319.2 6454.8 6457.2 6458.7 6429.4 6429.5 6432.7 6440.3 6475.2 6559.9 6567.7 6634.6 6658.2 6674.0 6256.1 6309.0 6315.9 6331.6 6452.9 6412.3 6427.0 6437.7 6441.7 6462.2 6333.6 6339.8 6356.2 6360.2 6370.8 6283.5 6382.2 6470.2 6502.3 6513.7 6165.1 6192.4 6254.8 6315.7 6336.2 16:53:06 INFO - PID 25726 | 16:53:06 INFO - PID 25726 | [#1] ext.html Cycles:180 Average:40235.83 Median:19890.77 stddev:55699.49 (280.0%) stddev-sans-first:55777.27 16:53:06 INFO - PID 25726 | Values: 861.3 877.9 909.1 909.6 1144.1 32668.3 33177.2 33187.5 44590.3 45271.0 44573.6 45363.5 45469.8 45484.3 45558.0 7668.3 7740.9 7891.9 7940.0 7940.0 7738.7 7821.1 7836.3 7841.5 7864.3 7805.8 7829.5 7870.2 7893.5 7899.4 7812.7 7818.2 7835.9 7870.4 7882.8 7721.0 7752.7 7770.5 7777.1 7778.9 41431.6 41486.6 41823.4 41845.0 41877.4 3575.0 3577.7 3609.1 3619.3 3620.4 32113.4 32279.7 32408.4 32455.8 32465.8 4807.2 4956.0 4959.2 4974.3 4975.6 197046.0 198145.0 198337.0 198548.0 199116.0 51446.0 51476.2 52338.0 52385.7 52393.2 53994.0 54013.5 54200.5 54279.0 54281.0 14530.3 16255.2 16297.2 16351.9 16510.0 11732.2 11822.5 13687.3 13819.7 13919.2 14986.2 15001.1 15163.9 15167.2 15285.7 5632.3 5647.2 5809.9 5911.9 5918.1 1004.2 1008.1 1014.6 1019.2 1034.9 23349.8 24069.6 29999.4 30152.2 30564.2 1091.7 1093.9 1094.1 1100.1 1103.4 12790.6 12850.6 12887.6 13047.5 13081.4 239396.0 239768.0 240733.0 242233.0 242269.0 57478.0 57532.7 57541.4 57793.4 57916.7 1462.0 1494.9 1498.8 1508.7 1522.8 24007.9 27938.4 28066.9 28867.6 28924.0 55048.0 55542.2 56077.0 56081.0 56095.0 201000.0 201773.0 202085.0 202579.0 204263.0 37456.4 38262.0 39835.2 40197.4 40302.4 47239.3 49587.3 49594.8 49736.0 49855.0 35538.0 35909.0 36038.3 36162.3 36203.8 35215.0 35230.3 35381.0 35674.3 35984.6 39681.0 39694.8 39899.4 40031.8 40130.4 34349.8 34789.5 34814.1 34983.3 35575.1 24708.6 25153.1 25415.2 25860.7 26150.1 16:53:06 INFO - PID 25726 | 16:53:06 INFO - PID 25726 | [#2] jquery.html Cycles:180 Average:35610.15 Median:23753.14 stddev:85182.80 (358.6%) stddev-sans-first:85381.16 16:53:06 INFO - PID 25726 | Values: 585.2 587.4 594.8 606.6 610.7 23032.9 23846.6 23872.3 23905.9 23936.9 18675.9 19951.3 22094.8 24175.5 24356.5 6544.9 6711.8 6711.8 6722.0 6723.9 5876.3 6540.4 6570.6 6598.2 6626.7 6364.3 6368.7 6374.7 6380.1 6409.5 6316.9 6360.8 6365.4 6378.4 6381.5 5842.8 5869.2 5898.7 5904.4 5910.2 29581.3 30444.8 30756.8 30875.8 30939.8 1869.5 1904.4 1923.4 1924.7 1926.7 19447.3 20080.3 20104.9 20120.3 20128.8 2304.6 2346.2 2355.4 2358.5 2459.4 125543.4 132165.0 132634.0 132891.0 138497.0 15083.5 15388.5 15563.5 15730.1 16357.3 24514.0 24540.6 24563.5 24608.6 24651.4 9999.8 10027.1 10134.8 10290.9 10338.0 9341.0 9420.7 9457.2 9459.7 9540.9 9161.6 9454.5 9578.2 9642.2 9834.5 2713.1 2726.5 2754.6 2755.6 2785.1 924.8 962.4 972.7 988.9 995.2 19427.7 19607.7 20116.5 23659.6 23870.6 1011.8 1062.9 1064.7 1066.7 1079.5 11552.5 11575.1 11586.8 11760.0 11760.8 500312.0 502136.0 511479.0 511670.0 512355.0 115225.0 120466.0 122051.0 122552.4 124342.0 1113.7 1189.6 1206.7 1240.0 1305.3 21184.9 21227.6 21447.9 21508.7 21556.5 57822.7 59080.0 59251.7 59581.4 60681.0 68480.7 68875.0 69287.0 69486.7 70098.6 14577.0 14865.2 15016.0 15034.4 15035.1 24125.2 24220.9 24268.4 24718.1 24740.5 18711.0 19290.7 19479.3 19866.0 19911.5 17453.6 17585.7 17872.3 18008.3 18045.5 19467.6 20046.7 20093.0 20245.9 20259.6 14965.4 14999.6 15077.5 15102.1 15124.2 18073.0 18653.4 18667.4 18678.7 18701.1 16:53:06 INFO - PID 25726 | 16:53:06 INFO - PID 25726 | [#3] mootools.html Cycles:180 Average:8699.76 Median:2629.48 stddev:5894.82 (224.2%) stddev-sans-first:5885.61 16:53:06 INFO - PID 25726 | Values: 1367.0 1385.9 1396.7 1414.3 1422.1 13593.9 14170.8 14176.1 14179.1 14179.8 13852.7 14620.6 14735.8 14777.0 14786.3 2698.2 2769.9 2772.3 2772.5 2773.3 2594.9 2620.9 2664.5 2757.0 2757.3 2746.3 2772.3 2773.5 2774.4 2774.9 2568.6 2611.8 2616.1 2629.8 2651.6 2597.3 2629.2 2653.2 2701.1 2705.7 13879.4 13987.1 13988.7 14004.2 14025.5 7388.4 7618.1 7618.7 7640.5 7648.4 4901.8 5072.1 5175.0 5232.2 5233.4 1806.7 1818.6 1826.6 1888.3 1895.9 23129.9 23362.9 23367.8 23374.6 23457.8 6538.7 6544.2 6561.8 6808.1 6846.3 15815.4 15835.9 15857.0 15858.4 15876.1 7851.8 8168.0 8297.4 8297.5 8302.2 6845.2 6894.4 6896.6 6998.7 7383.7 4915.2 5086.5 5118.9 5133.6 5162.9 1469.3 1470.6 1491.8 1497.3 1499.5 908.7 950.3 955.1 956.2 958.3 7566.5 7578.0 7606.3 7630.8 7742.8 726.3 745.2 749.6 750.8 759.9 4991.7 4994.2 5059.4 5125.1 5320.8 18113.6 18508.4 19078.0 19081.8 19107.5 18792.2 18841.0 18859.0 18984.2 19205.5 9625.2 10323.8 10641.0 10644.0 10660.6 8177.7 8191.3 8194.5 8207.1 8241.1 16569.5 16570.7 16729.9 16843.0 17443.6 16884.6 17175.2 17192.4 17210.1 17266.6 10813.5 10991.3 11047.7 11065.2 11065.6 12863.1 12957.7 12968.7 13048.1 13119.8 10471.1 10548.0 10584.5 10620.2 10750.7 10362.9 10396.6 10431.2 10444.5 10714.9 9243.5 9439.5 9466.8 9475.0 9481.7 6930.3 6986.4 7048.9 7371.1 7436.5 7140.9 7217.7 7218.9 7248.7 7436.9 16:53:06 INFO - PID 25726 | 16:53:06 INFO - PID 25726 | [#4] prototype.html Cycles:180 Average:5637.71 Median:6120.53 stddev:1509.83 (24.7%) stddev-sans-first:1463.92 16:53:06 INFO - PID 25726 | Values: 496.3 502.9 513.5 526.4 531.9 6286.7 6290.4 6292.8 6332.7 6376.4 5942.6 6163.9 6173.3 6256.6 6260.7 5190.0 5206.2 5209.6 5215.8 5249.0 5153.2 5178.6 5210.2 5234.7 5239.9 4973.0 5108.4 5140.2 5157.9 5230.5 5094.8 5136.5 5143.2 5147.7 5186.8 4786.5 4805.6 4825.4 4832.6 4846.4 5979.8 6020.9 6022.0 6049.5 6068.8 5973.8 6044.5 6045.0 6066.4 6071.9 6587.8 6598.9 6611.3 6701.1 6723.7 5270.5 5294.0 5316.6 5336.2 5372.8 6953.6 7018.6 7075.4 7081.0 7092.6 5495.4 5524.8 5561.7 5582.4 5585.8 5551.9 5569.0 5583.0 5606.8 5646.7 6050.3 6051.8 6052.3 6060.1 6077.1 6692.0 6712.0 6716.2 6738.2 6768.2 1853.7 1898.9 1917.9 1963.8 1969.3 1375.8 1436.6 1454.1 1459.7 1482.1 5730.0 5760.1 5773.4 5783.7 5791.6 6654.3 6666.9 6693.7 6695.3 6730.3 5664.4 5779.8 5797.7 5829.8 5873.5 3601.9 3634.6 3639.5 3647.9 3653.9 6364.6 6414.5 6424.1 6504.7 6544.9 6847.9 6883.7 6929.8 6940.7 6952.4 6545.2 6591.7 6598.6 6609.3 6641.1 6732.6 6737.9 6743.6 6760.5 6783.3 6508.2 6518.2 6622.5 6693.5 6697.9 6435.2 6564.2 6582.1 6618.2 6694.4 4972.2 5066.6 5085.5 5089.6 5091.7 6706.9 6784.0 6784.6 6828.7 6858.5 6537.2 6550.9 6571.2 6584.8 6619.7 6398.2 6514.3 6530.1 6538.0 6548.9 6247.6 6425.6 6459.2 6549.7 6557.3 6421.9 6521.0 6575.6 6597.0 6625.0 6347.9 6391.1 6402.6 6410.1 6442.5 16:53:06 INFO - PID 25726 | 16:53:06 INFO - PID 25726 | [#5] yui.html Cycles:180 Average:21663.11 Median:20235.89 stddev:29806.41 (147.3%) stddev-sans-first:29849.28 16:53:06 INFO - PID 25726 | Values: 905.2 910.1 927.6 934.7 935.4 18102.2 18955.1 18978.6 19037.4 20424.2 20941.7 21702.5 21793.9 21827.1 21901.0 1016.8 1040.3 1040.8 1043.0 1044.8 1042.9 1042.9 1043.4 1045.4 1046.0 985.4 1050.8 1107.0 1107.5 1108.8 1095.7 1098.3 1101.7 1102.0 1103.4 789.2 790.9 791.1 794.6 796.7 18443.7 18561.8 18735.9 18798.8 20047.6 20579.3 20676.5 21039.5 21211.6 21218.3 14528.6 15660.7 15732.4 15838.8 15847.9 3544.7 3563.8 3579.3 3585.7 3586.2 118950.0 120516.0 122184.0 124087.0 126698.4 15622.5 16269.3 16411.8 16585.3 16608.8 29700.3 29717.7 30335.0 30415.7 30467.4 8376.2 8530.1 8557.5 8567.4 8596.9 4019.9 4090.1 4098.7 4099.9 4151.9 5236.8 5295.8 5298.3 5352.1 5357.2 994.3 1018.2 1028.7 1033.6 1034.7 640.8 652.5 657.7 660.4 666.4 10606.2 10954.6 11069.9 11203.3 11780.3 670.3 670.8 675.7 685.9 687.0 4571.8 4622.6 4631.3 4632.0 4653.3 98267.5 101318.0 101514.0 105416.0 105749.0 88041.6 90751.0 90945.0 91156.0 93956.5 78510.6 80879.6 81235.6 82318.6 82794.0 8859.4 8911.3 8972.8 9009.6 9089.4 35215.6 35317.0 35389.0 35562.6 36634.0 44499.4 44703.1 45375.1 46135.5 46164.8 13788.1 13890.9 13976.7 14088.2 14114.2 15930.8 15936.3 16002.1 16020.1 16227.4 14337.2 14398.6 14493.3 14510.6 14597.3 14486.0 14638.2 14661.9 14783.9 14790.9 17177.9 17563.7 18187.4 18202.5 18362.0 13630.3 13679.7 13790.8 13945.7 13953.1 13707.9 13803.2 13842.8 14061.8 14098.4 16:53:06 INFO - PID 25726 | -------- Summary: end -------- 16:53:06 INFO - PID 25726 | 16:53:07 INFO - TEST-INFO | 25726: exit 0 16:53:07 INFO - TEST-OK | dromaeo_css | took 1113236ms 16:53:07 INFO - TEST-START | kraken 16:53:07 INFO - Initialising browser for kraken test... 16:53:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54249/getInfo.html -profile /tmp/tmpTYsDGE/profile 16:53:07 INFO - TEST-INFO | started process 26096 (/builds/slave/test/build/application/firefox/firefox http://localhost:54249/getInfo.html) 16:53:13 INFO - TEST-INFO | 26096: exit 0 16:53:13 INFO - Browser initialized. 16:53:13 INFO - Running cycle 1/1 for kraken test... 16:53:13 INFO - TEST-INFO | started process 26323 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTYsDGE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:53:14 INFO - PID 26323 | 16:53:14 INFO - PID 26323 | (/builds/slave/test/build/application/firefox/firefox:26378): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:53:14 INFO - PID 26323 | 16:53:24 INFO - PID 26323 | RSS: Main: 174616576 16:53:24 INFO - PID 26323 | 16:53:27 INFO - PID 26323 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54249/tests/kraken/driver.html?testName=audio-beat-detection) 16:53:28 INFO - PID 26323 | RSS: Main: 179580928 16:53:28 INFO - PID 26323 | 16:53:30 INFO - PID 26323 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54249/tests/kraken/driver.html?testName=audio-dft) 16:53:31 INFO - PID 26323 | RSS: Main: 180080640 16:53:31 INFO - PID 26323 | 16:53:34 INFO - PID 26323 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54249/tests/kraken/driver.html?testName=audio-fft) 16:53:35 INFO - PID 26323 | RSS: Main: 174772224 16:53:35 INFO - PID 26323 | 16:53:37 INFO - PID 26323 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54249/tests/kraken/driver.html?testName=audio-oscillator) 16:53:38 INFO - PID 26323 | RSS: Main: 175263744 16:53:38 INFO - PID 26323 | 16:53:40 INFO - PID 26323 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:53:40 INFO - PID 26323 | RSS: Main: 175403008 16:53:40 INFO - PID 26323 | 16:53:44 INFO - PID 26323 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54249/tests/kraken/driver.html?testName=imaging-darkroom) 16:53:44 INFO - PID 26323 | RSS: Main: 178511872 16:53:44 INFO - PID 26323 | 16:53:48 INFO - PID 26323 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54249/tests/kraken/driver.html?testName=imaging-desaturate) 16:53:48 INFO - PID 26323 | RSS: Main: 177762304 16:53:48 INFO - PID 26323 | 16:53:51 INFO - PID 26323 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54249/tests/kraken/driver.html?testName=json-parse-financial) 16:53:52 INFO - PID 26323 | RSS: Main: 178528256 16:53:52 INFO - PID 26323 | 16:53:54 INFO - PID 26323 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:53:54 INFO - PID 26323 | RSS: Main: 178266112 16:53:54 INFO - PID 26323 | 16:53:56 INFO - PID 26323 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54249/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:53:57 INFO - PID 26323 | RSS: Main: 175706112 16:53:57 INFO - PID 26323 | 16:53:59 INFO - PID 26323 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:54:00 INFO - PID 26323 | RSS: Main: 175796224 16:54:00 INFO - PID 26323 | 16:54:02 INFO - PID 26323 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:54:03 INFO - PID 26323 | RSS: Main: 176267264 16:54:03 INFO - PID 26323 | 16:54:06 INFO - PID 26323 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:54:07 INFO - PID 26323 | RSS: Main: 176365568 16:54:07 INFO - PID 26323 | 16:54:09 INFO - PID 26323 | Cycle 1(1): loaded http://localhost:54249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54249/tests/kraken/driver.html?testName=ai-astar) 16:54:09 INFO - PID 26323 | RSS: Main: 177553408 16:54:09 INFO - PID 26323 | 16:54:09 INFO - PID 26323 | __start_tp_report 16:54:09 INFO - PID 26323 | _x_x_mozilla_page_load 16:54:09 INFO - PID 26323 | _x_x_mozilla_page_load_details 16:54:09 INFO - PID 26323 | |i|pagename|runs| 16:54:09 INFO - PID 26323 | |0;ai-astar;100;107;101;107;107;108;110;101;107;111 16:54:09 INFO - PID 26323 | |1;audio-beat-detection;162;133;144;190;118;147;156;125;165;119 16:54:09 INFO - PID 26323 | |2;audio-dft;162;164;166;162;165;163;164;165;163;167 16:54:09 INFO - PID 26323 | |3;audio-fft;79;76;76;165;83;74;74;75;146;76 16:54:09 INFO - PID 26323 | |4;audio-oscillator;83;82;130;89;84;79;124;78;87;128 16:54:09 INFO - PID 26323 | |5;imaging-gaussian-blur;114;114;116;118;114;114;114;114;117;114 16:54:09 INFO - PID 26323 | |6;imaging-darkroom;152;151;151;151;152;149;155;152;150;150 16:54:09 INFO - PID 26323 | |7;imaging-desaturate;94;94;101;94;96;94;96;94;97;94 16:54:09 INFO - PID 26323 | |8;json-parse-financial;56;69;58;60;61;57;56;62;56;61 16:54:09 INFO - PID 26323 | |9;json-stringify-tinderbox;55;54;55;60;54;54;54;54;54;58 16:54:09 INFO - PID 26323 | |10;stanford-crypto-aes;83;88;89;77;76;92;86;76;78;78 16:54:09 INFO - PID 26323 | |11;stanford-crypto-ccm;127;141;129;129;124;141;131;136;135;168 16:54:09 INFO - PID 26323 | |12;stanford-crypto-pbkdf2;176;182;175;188;179;183;171;182;184;180 16:54:09 INFO - PID 26323 | |13;stanford-crypto-sha256-iterative;67;65;66;70;65;71;68;66;67;66 16:54:09 INFO - PID 26323 | __end_tp_report 16:54:09 INFO - PID 26323 | __start_cc_report 16:54:09 INFO - PID 26323 | _x_x_mozilla_cycle_collect,983 16:54:09 INFO - PID 26323 | __end_cc_report 16:54:09 INFO - PID 26323 | __startTimestamp1501718049450__endTimestamp 16:54:09 INFO - PID 26323 | 16:54:09 INFO - PID 26323 | ------- Summary: start ------- 16:54:09 INFO - PID 26323 | Number of tests: 14 16:54:09 INFO - PID 26323 | 16:54:09 INFO - PID 26323 | [#0] ai-astar Cycles:10 Average:105.90 Median:107.00 stddev:3.87 (3.6%) stddev-sans-first:3.47 16:54:09 INFO - PID 26323 | Values: 100.0 107.0 101.0 107.0 107.0 108.0 110.0 101.0 107.0 111.0 16:54:09 INFO - PID 26323 | 16:54:09 INFO - PID 26323 | [#1] audio-beat-detection Cycles:10 Average:145.90 Median:151.50 stddev:23.05 (15.2%) stddev-sans-first:23.70 16:54:09 INFO - PID 26323 | Values: 162.0 133.0 144.0 190.0 118.0 147.0 156.0 125.0 165.0 119.0 16:54:09 INFO - PID 26323 | 16:54:09 INFO - PID 26323 | [#2] audio-dft Cycles:10 Average:164.10 Median:164.50 stddev:1.66 (1.0%) stddev-sans-first:1.58 16:54:09 INFO - PID 26323 | Values: 162.0 164.0 166.0 162.0 165.0 163.0 164.0 165.0 163.0 167.0 16:54:09 INFO - PID 26323 | 16:54:09 INFO - PID 26323 | [#3] audio-fft Cycles:10 Average:92.40 Median:76.00 stddev:33.66 (44.3%) stddev-sans-first:35.35 16:54:09 INFO - PID 26323 | Values: 79.0 76.0 76.0 165.0 83.0 74.0 74.0 75.0 146.0 76.0 16:54:09 INFO - PID 26323 | 16:54:09 INFO - PID 26323 | [#4] audio-oscillator Cycles:10 Average:96.40 Median:82.50 stddev:21.64 (26.2%) stddev-sans-first:22.40 16:54:09 INFO - PID 26323 | Values: 83.0 82.0 130.0 89.0 84.0 79.0 124.0 78.0 87.0 128.0 16:54:09 INFO - PID 26323 | 16:54:09 INFO - PID 26323 | [#5] imaging-gaussian-blur Cycles:10 Average:114.90 Median:114.00 stddev:1.52 (1.3%) stddev-sans-first:1.58 16:54:09 INFO - PID 26323 | Values: 114.0 114.0 116.0 118.0 114.0 114.0 114.0 114.0 117.0 114.0 16:54:09 INFO - PID 26323 | 16:54:09 INFO - PID 26323 | [#6] imaging-darkroom Cycles:10 Average:151.30 Median:151.50 stddev:1.64 (1.1%) stddev-sans-first:1.72 16:54:09 INFO - PID 26323 | Values: 152.0 151.0 151.0 151.0 152.0 149.0 155.0 152.0 150.0 150.0 16:54:09 INFO - PID 26323 | 16:54:09 INFO - PID 26323 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:94.00 stddev:2.27 (2.4%) stddev-sans-first:2.35 16:54:09 INFO - PID 26323 | Values: 94.0 94.0 101.0 94.0 96.0 94.0 96.0 94.0 97.0 94.0 16:54:09 INFO - PID 26323 | 16:54:09 INFO - PID 26323 | [#8] json-parse-financial Cycles:10 Average:59.60 Median:60.50 stddev:4.03 (6.7%) stddev-sans-first:4.06 16:54:09 INFO - PID 26323 | Values: 56.0 69.0 58.0 60.0 61.0 57.0 56.0 62.0 56.0 61.0 16:54:09 INFO - PID 26323 | 16:54:09 INFO - PID 26323 | [#9] json-stringify-tinderbox Cycles:10 Average:55.20 Median:54.50 stddev:2.10 (3.8%) stddev-sans-first:2.22 16:54:09 INFO - PID 26323 | Values: 55.0 54.0 55.0 60.0 54.0 54.0 54.0 54.0 54.0 58.0 16:54:09 INFO - PID 26323 | 16:54:09 INFO - PID 26323 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:84.50 stddev:6.06 (7.2%) stddev-sans-first:6.42 16:54:09 INFO - PID 26323 | Values: 83.0 88.0 89.0 77.0 76.0 92.0 86.0 76.0 78.0 78.0 16:54:09 INFO - PID 26323 | 16:54:09 INFO - PID 26323 | [#11] stanford-crypto-ccm Cycles:10 Average:136.10 Median:135.50 stddev:12.57 (9.3%) stddev-sans-first:12.90 16:54:09 INFO - PID 26323 | Values: 127.0 141.0 129.0 129.0 124.0 141.0 131.0 136.0 135.0 168.0 16:54:09 INFO - PID 26323 | 16:54:09 INFO - PID 26323 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.00 Median:182.00 stddev:4.94 (2.7%) stddev-sans-first:5.03 16:54:09 INFO - PID 26323 | Values: 176.0 182.0 175.0 188.0 179.0 183.0 171.0 182.0 184.0 180.0 16:54:09 INFO - PID 26323 | 16:54:09 INFO - PID 26323 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:2.02 (3.0%) stddev-sans-first:2.15 16:54:09 INFO - PID 26323 | Values: 67.0 65.0 66.0 70.0 65.0 71.0 68.0 66.0 67.0 66.0 16:54:09 INFO - PID 26323 | -------- Summary: end -------- 16:54:09 INFO - PID 26323 | 16:54:09 INFO - PID 26323 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:54:10 INFO - TEST-INFO | 26323: exit 0 16:54:10 INFO - TEST-OK | kraken | took 62987ms 16:54:10 INFO - SUITE-END | took 1176s 16:54:10 INFO - Completed test suite (00:19:36) 16:54:10 INFO - javascript benchmark 16:54:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8503.313033237724, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3081.445339838828, 3114.8442577871106, 3118.750499960003, 3122.0789866989235, 3151.6690747471516, 6059.636421814695, 6084.756609735611, 6166.722497487618, 6195.659238741868, 6206.7827626033095, 6014.669193194372, 6091.939080609192, 6096.329403765582, 6131.754729810802, 6154.323024467316, 5575.191597218401, 5633.042382794934, 5645.576581756364, 5669.971650141743, 5686.9146962795385, 5523.502884740373, 5527.751251193706, 5558.388577256498, 5560.888782224353, 5593.496585307322, 5533.418991005947, 5540.63985840921, 5548.9722551386985, 5549.6115271930985, 5557.638753481031, 5532.972335138339, 5536.640118392332, 5538.363088244863, 5540.445955404468, 5541.307336582927, 5456.536194423479, 5513.889722205574, 5517.310336207974, 5527.751251193706, 5543.528800052, 5980.8504787380225, 6023.427774361428, 6023.849403764897, 6033.306169790487, 6048.727807248684, 6450.064740612632, 6464.45052170566, 6473.83815404614, 6481.09264702942, 6482.870342593169, 6592.604443733391, 6592.912169492028, 6606.834829129262, 6685.832854178636, 6700.095487109226, 5997.550183736237, 6128.479079278267, 6171.506279497629, 6183.938160618426, 6205.596636218631, 6718.294579069225, 6719.193696756421, 6730.596164230162, 6737.292584278678, 6768.7292508299115, 6450.903236451457, 6453.0, 6472.385123413268, 6486.837829054311, 6489.286178520359, 6472.15861937945, 6577.177852768404, 6579.407853293226, 6604.537682362285, 6609.372109649576, 6431.839204019937, 6486.740530378732, 6502.707378167994, 6535.248446428722, 6548.639824809586, 6446.77436289737, 6518.771842985473, 6525.967370163119, 6542.378474044958, 6578.901316480256, 5219.086659834514, 5308.708021058879, 5323.121684922022, 5335.386430560535, 5337.6797392156595, 4587.105514424655, 4602.631789456836, 4609.377734005933, 4618.468876079227, 4648.372469716613, 5701.372848986608, 5703.372629010805, 5764.221830052973, 5766.538676905838, 5787.536997040227, 6438.066480360415, 6551.868962620721, 6572.0, 6575.934240657628, 6666.933330666631, 5640.520555752725, 5643.717814109278, 5644.915326270109, 5655.208270842086, 5663.518600918968, 5713.885722285614, 5726.656400615976, 5741.052726300114, 5751.338596061423, 5800.4489573490455, 6430.3891130342545, 6436.356364363526, 6444.065610486453, 6447.806565803034, 6459.870802583922, 6573.967130164318, 6606.768763093269, 6636.369544893228, 6640.90038649411, 6641.070250164884, 6222.284437289589, 6319.178506794087, 6454.806355809334, 6457.160569125984, 6458.741650333934, 6429.38920802523, 6429.485641148791, 6432.678366081584, 6440.29156792743, 6475.222973243052, 6559.868802624016, 6567.671616419065, 6634.635095069818, 6658.201015878126, 6674.032265321404, 6256.092866534418, 6309.0, 6315.905261421174, 6331.620102793847, 6452.870942581216, 6412.326705696021, 6427.035944608159, 6437.742490300336, 6441.742330306923, 6462.159919210472, 6333.588316759396, 6339.778107766206, 6356.2054743157105, 6360.173177486897, 6370.840728981812, 6283.465905398092, 6382.170317858649, 6470.191226096738, 6502.252240992347, 6513.7068831903625, 6165.136880836776, 6192.442680158807, 6254.843628909313, 6315.684215789284, 6336.20797400325], "value": 5981.698261389077, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [861.310951239009, 877.8814859993901, 909.1272378516621, 909.58613830707, 1144.1476100305274, 32668.346633067355, 33177.17057073571, 33187.502187467144, 44590.33114503277, 45271.0, 44573.55426445734, 45363.54636453634, 45469.7726511367, 45484.317735234, 45558.0, 7668.271514206142, 7740.92259077409, 7891.881621775649, 7939.960300198491, 7940.047194336687, 7738.690452381898, 7821.061472623293, 7836.2947334739865, 7841.490303130278, 7864.252895974902, 7805.804854878645, 7829.451938364316, 7870.173631768667, 7893.526388416684, 7899.368050555941, 7812.687492500321, 7818.218178182193, 7835.882461763078, 7870.370370370357, 7882.842343153105, 7721.03487064117, 7752.728654497066, 7770.4560680752365, 7777.066751989741, 7778.8833167502535, 41431.58568414338, 41486.585134148874, 41823.37264941028, 41845.0, 41877.371839422434, 3575.0, 3577.695895848856, 3609.1157666371646, 3619.2580520993133, 3620.384534629093, 32113.357732845212, 32279.6772032279, 32408.35183296321, 32455.837720811243, 32465.837670811496, 4807.18277892759, 4956.0, 4959.231319145538, 4974.303597496353, 4975.62682798788, 197046.0, 198145.0, 198337.0, 198548.0, 199116.00441997842, 51446.0, 51476.22785658218, 52338.0, 52385.7380713094, 52393.2141017885, 53994.0, 54013.45986540163, 54200.45799542033, 54279.0, 54281.0, 14530.273486325852, 16255.18724063792, 16297.185140743153, 16351.918240408722, 16510.0, 11732.178747487595, 11822.527098915947, 13687.315634218448, 13819.723605527834, 13919.164850109026, 14986.175760333286, 15001.099934003994, 15163.924180379028, 15167.165805880783, 15285.694286114216, 5632.295963004624, 5647.1529270609735, 5809.912851307234, 5911.852203694856, 5918.112283157561, 1004.1665417703289, 1008.0977525115128, 1014.6195176808696, 1019.1744686803713, 1034.9172066234682, 23349.7665023351, 24069.63895541568, 29999.400011999638, 30152.24619384533, 30564.235894102825, 1091.7434402915308, 1093.9015488606065, 1094.0973696700096, 1100.1254003067581, 1103.426218366461, 12790.61628151157, 12850.614481565568, 12887.613371598867, 13047.543335983195, 13081.41133648988, 239396.0, 239768.0, 240733.0, 242233.0, 242269.0, 57478.0, 57532.71233643805, 57541.424585754445, 57793.4220657788, 57916.71041644765, 1461.9707605847823, 1494.9177795221367, 1498.820141583017, 1508.7284288827898, 1522.8477152284684, 24007.879960600087, 27938.441231175668, 28066.85966570236, 28867.56698649564, 28923.987660431783, 55048.0, 55542.16686749621, 56076.99999999837, 56081.0, 56095.0, 201000.0, 201773.0, 202085.0, 202579.0, 204263.0, 37456.438153427174, 38262.0, 39835.20329593334, 40197.39703904502, 40302.39546406865, 47239.29141062818, 49587.25619115788, 49594.752026239636, 49736.0, 49855.0, 35538.0, 35909.0, 36038.279234414644, 36162.27675446529, 36203.818980905984, 35215.0, 35230.295394092485, 35381.0, 35674.28651427009, 35984.64015359813, 39681.0, 39694.80152599219, 39899.40150897797, 40031.79984100061, 40130.39804402995, 34349.82825085858, 34789.47815782816, 34814.12964675904, 34983.300333992665, 35575.11062223465, 24708.629370559098, 25153.119640812485, 25415.19854062053, 25860.741392586588, 26150.08474703376], "value": 18258.64472468849, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.2041223935449, 587.3509771702268, 594.7977687586219, 606.5753972219447, 610.7068607068607, 23032.88483557582, 23846.642300365485, 23872.283831485063, 23905.880470597665, 23936.8803155984, 18675.906620466914, 19951.301704440346, 22094.779052209473, 24175.516489670197, 24356.512869742637, 6544.869102617945, 6711.765088221913, 6711.798646040626, 6721.966390168042, 6723.899141512881, 5876.265466816648, 6540.4440622547145, 6570.605763654172, 6598.208215014205, 6626.7017984190825, 6364.299927008025, 6368.745250189987, 6374.74501019961, 6380.106785050097, 6409.455196308296, 6316.936830631681, 6360.809175724736, 6365.427111559959, 6378.362163783631, 6381.457576106037, 5842.824715258549, 5869.2369991901205, 5898.734556944905, 5904.409559044104, 5910.231669882931, 29581.260468488246, 30444.8477757612, 30756.84621576878, 30875.845620771976, 30939.84530077335, 1869.457857221404, 1904.4096330137563, 1923.3556758485784, 1924.6824273994773, 1926.6820974539185, 19447.3193438229, 20080.29718959844, 20104.899475502676, 20120.29578964729, 20128.79871201284, 2304.5506126305377, 2346.21401830387, 2355.36405170603, 2358.469344397514, 2459.446624509489, 125543.372283138, 132165.0, 132634.0, 132891.0, 138497.0, 15083.54749357521, 15388.53834384948, 15563.455279065178, 15730.056196628238, 16357.263923123488, 24514.0, 24540.631890521658, 24563.508729825662, 24608.630870536956, 24651.3837154069, 9999.800003999879, 10027.147692446079, 10134.797304053878, 10290.948545257375, 10338.0, 9341.0, 9420.670276540426, 9457.243420526342, 9459.722548084794, 9540.904590954002, 9161.633534658673, 9454.527273636428, 9578.185854202331, 9642.228621710387, 9834.459104749307, 2713.1182365731215, 2726.4955983142786, 2754.5868119781794, 2755.6301580640634, 2785.1226863537922, 924.7641851327868, 962.3648392061345, 972.6644307713827, 988.9109980101825, 995.1988649137369, 19427.708584371245, 19607.705884411746, 20116.497087572927, 23659.645105323434, 23870.554150287764, 1011.7824667696333, 1062.914966802654, 1064.7178497698117, 1066.664000839733, 1079.5142186016167, 11552.480138393792, 11575.073994080454, 11586.76826463466, 11760.0, 11760.76478470443, 500312.0, 502136.0, 511479.0, 511670.0, 512355.0, 115225.0, 120466.0, 122051.0, 122552.38723806503, 124342.0, 1113.7494063835572, 1189.5658084799159, 1206.740550781586, 1240.0, 1305.3473263368505, 21184.894075529523, 21227.575448490636, 21447.8927605361, 21508.677369839777, 21556.461088472915, 57822.7108864453, 59080.0, 59251.70374148102, 59581.40418595759, 60681.0, 68480.73467758115, 68875.0, 69287.0, 69486.65256673684, 70098.64950675418, 14577.0, 14865.182414967281, 15016.0, 15034.398624054915, 15035.097894126387, 24125.155619553232, 24220.87889560541, 24268.39329016718, 24718.13486527963, 24740.505189896463, 18711.0, 19290.7106393407, 19479.318223862098, 19866.0, 19911.502212444804, 17453.563660908072, 17585.736213956545, 17872.28510859551, 18008.279668813102, 18045.548861278574, 19467.61064778725, 20046.699299510812, 20092.995350232723, 20245.898770506643, 20259.594808103462, 14965.401383944956, 14999.550013499613, 15077.54767356981, 15102.093874367572, 15124.168170750714, 18073.0, 18653.44039678812, 18667.439976800717, 18678.71981920245, 18701.06494675288], "value": 12207.668970345288, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.9863301366986, 1385.8544852790458, 1396.692727599928, 1414.271650100198, 1422.1396055386485, 13593.864061359396, 14170.787438188423, 14176.149431034146, 14179.078359906598, 14179.78730319046, 13852.722945541083, 14620.634484137901, 14735.778963315532, 14777.0, 14786.334614942327, 2698.190542837151, 2769.8892044318204, 2772.279207406069, 2772.487089888367, 2773.292810333362, 2594.9481010379877, 2620.882060307291, 2664.4937461882278, 2757.0212574536054, 2757.2555410039276, 2746.3408781892304, 2772.279207406079, 2773.5285001549787, 2774.3618967637453, 2774.8612569371553, 2568.5633442314756, 2611.791056715458, 2616.0582190411437, 2629.8027647926388, 2651.562492188796, 2597.298729343067, 2629.1586692258484, 2653.230563136688, 2701.149138021517, 2705.675318961737, 13879.4448222072, 13987.090839095528, 13988.72022559543, 14004.159750415009, 14025.509107181302, 7388.3719883809945, 7618.085829700472, 7618.695252189906, 7640.503367281109, 7648.388128949727, 4901.828436004723, 5072.061668591323, 5175.042617115846, 5232.1628539433505, 5233.371995360543, 1806.719958406449, 1818.5544541587396, 1826.56162520994, 1888.2541396148445, 1895.895725735084, 23129.88435057814, 23362.883185584134, 23367.76632233673, 23374.64938025931, 23457.7654223459, 6538.738450461929, 6544.247411547638, 6561.770338038241, 6808.11494505721, 6846.315368463114, 15815.446459373867, 15835.920820395824, 15857.048577085412, 15858.365665373256, 15876.126813025394, 7851.764447066597, 8168.0, 8297.377696672773, 8297.543635100006, 8302.169783021649, 6845.212800527904, 6894.413974812097, 6896.586204827727, 6998.650067496605, 7383.6677349519405, 4915.213565829522, 5086.4659210783075, 5118.87202819925, 5133.563647089964, 5162.948370516322, 1469.2800527741545, 1470.5661829760247, 1491.7687758397465, 1497.3262032085604, 1499.4601943300404, 908.6592527802075, 950.3442624589012, 955.1069749783974, 956.1872408452759, 958.3483231402713, 7566.508176968479, 7578.0, 7606.315431611181, 7630.8092297693, 7742.806429839186, 726.284609659485, 745.1542499263267, 749.636426333228, 750.7935317787652, 759.8556274307864, 4991.650584459126, 4994.1759609663995, 5059.367579052619, 5125.103106956268, 5320.76056577455, 18113.637727245383, 18508.44474665762, 19078.0, 19081.80918190828, 19107.522311942033, 18792.154353054146, 18841.0, 18859.0, 18984.240630374905, 19205.519862003282, 9625.219334423155, 10323.793524129474, 10641.0, 10644.0, 10660.573577056832, 8177.713780017671, 8191.303739182236, 8194.549299788569, 8207.097219305992, 8241.134680858664, 16569.502914912573, 16570.66858662796, 16729.91635041817, 16843.0, 17443.56391090233, 16884.57788555296, 17175.227114780115, 17192.398266060627, 17210.13949302505, 17266.568335791704, 10813.51339189754, 10991.340519568852, 11047.72380690489, 11065.170112241774, 11065.557377704801, 12863.101556309439, 12957.740845182854, 12968.740625187633, 13048.086633935913, 13119.803202951773, 10471.109955653856, 10548.0, 10584.523696433525, 10620.20348473852, 10750.677479675622, 10362.948185259025, 10396.58413663445, 10431.16550675929, 10444.477776111013, 10714.946425267824, 9243.491607961627, 9439.528023598656, 9466.810663786824, 9475.0, 9481.715548533555, 6930.306969303231, 6986.406155476638, 7048.894266586108, 7371.115466143882, 7436.479446438698, 7140.857182856417, 7217.6752046157, 7218.89171662436, 7248.673809678472, 7436.888446673413], "value": 6353.420797509412, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.3175633503932, 502.8767951851797, 513.5121634447275, 526.3972751199876, 531.9441458646842, 6286.748530058792, 6290.4024117708805, 6292.779752708656, 6332.651704156269, 6376.394242546957, 5942.554308426864, 6163.907541386871, 6173.32093469718, 6256.562040657144, 6260.718267677954, 5190.0, 5206.193040078784, 5209.583233341343, 5215.765290561915, 5248.973755131219, 5153.1754919212935, 5178.608011752308, 5210.218467229908, 5234.6859188448625, 5239.895202095956, 4972.950270497285, 5108.361454818148, 5140.1518749406305, 5157.871053223681, 5230.450802665722, 5094.828189516432, 5136.534463457561, 5143.202803565453, 5147.716875571842, 5186.792528298901, 4786.52134786522, 4805.6155507559315, 4825.396825396825, 4832.589229915461, 4846.369971903665, 5979.790707325266, 6020.879582408371, 6021.969890150565, 6049.516038716892, 6068.753217028869, 5973.790917317917, 6044.516438684895, 6044.96977515114, 6066.393360663942, 6071.907056729787, 6587.835304117388, 6598.93401065988, 6611.347163209198, 6701.128853249095, 6723.697433615499, 5270.49930256625, 5294.0, 5316.627836051478, 5336.226247194135, 5372.785088596452, 6953.617551034691, 7018.649067546602, 7075.363217310467, 7080.964595177094, 7092.609906455143, 5495.422980587061, 5524.8342549723575, 5561.6663000220115, 5582.385937546866, 5585.804496842672, 5551.916721249185, 5568.972155139199, 5582.972085139549, 5606.794539174097, 5646.661200327993, 6050.27567143362, 6051.8487037823525, 6052.334243233241, 6060.121282414026, 6077.1491991121275, 6692.0, 6712.0, 6716.227633822075, 6738.157730283715, 6768.172593399754, 1853.731208974691, 1898.8860668359666, 1917.8561607879185, 1963.783983761785, 1969.3304276545757, 1375.7592421326428, 1436.6480212347833, 1454.120257244352, 1459.7372472954758, 1482.0885155629426, 5729.9713501432225, 5760.135979603009, 5773.422657734193, 5783.739731712083, 5791.62354446968, 6654.334566543307, 6666.933330666631, 6693.665316734144, 6695.330466953363, 6730.259671436138, 5664.433556644385, 5779.797707080232, 5797.652140871562, 5829.795957141479, 5873.471387575139, 3601.891943241707, 3634.5638523377365, 3639.5086663300644, 3647.908802279911, 3653.9269214616093, 6364.586301890362, 6414.5189110815945, 6424.1327420798525, 6504.739810407626, 6544.887369147256, 6847.897281540781, 6883.65581720912, 6929.787287224715, 6940.687669054904, 6952.443804495628, 6545.247296560957, 6591.747567962072, 6598.571092878948, 6609.272979972296, 6641.136652235178, 6732.562383445061, 6737.86524269502, 6743.629100399525, 6760.459163267025, 6783.321667833177, 6508.219013718384, 6518.217813862367, 6622.470202383702, 6693.531452798257, 6697.866042679022, 6435.163428754232, 6564.245111812203, 6582.144321238208, 6618.172728408949, 6694.397504224643, 4972.154733695208, 5066.59467242627, 5085.516875896932, 5089.589974717022, 5091.745412729423, 6706.932930670826, 6784.0, 6784.59292442455, 6828.692708828214, 6858.525417035361, 6537.182852143482, 6550.934490655032, 6571.211454625475, 6584.806368910979, 6619.657073178207, 6398.200224971879, 6514.348565143638, 6530.106269181884, 6538.0, 6548.901766473411, 6247.593906396251, 6425.646589437626, 6459.224893012869, 6549.7380104795275, 6557.311482294291, 6421.903671445026, 6521.043686893818, 6575.605463672195, 6597.0, 6624.966875165594, 6347.857385670623, 6391.069492262132, 6402.583832050902, 6410.070539771614, 6442.549021568445], "value": 5202.948558092447, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [905.2079430498314, 910.053544313914, 927.5594092805923, 934.7476181431014, 935.4293880732754, 18102.185401656923, 18955.052247387626, 18978.62042759144, 19037.42887713371, 20424.183032678677, 20941.685874711893, 21702.457438564044, 21793.891030544866, 21827.126914923425, 21901.0, 1016.8373060310371, 1040.2614143957799, 1040.7658276887678, 1043.0247718383298, 1044.8067107585084, 1042.8800687920873, 1042.9009244121796, 1043.3896170740127, 1045.3884477580625, 1046.0271947089204, 985.3939827006411, 1050.8213603687354, 1107.0258172807958, 1107.5293000474805, 1108.794872948503, 1095.6932059023447, 1098.2696506822992, 1101.7025403141147, 1101.983470247947, 1103.3876198709713, 789.2147313423123, 790.8892755014302, 791.1060501633175, 794.6463823598501, 796.665400531778, 18443.723344149716, 18561.814381856144, 18735.906320468446, 18798.812011879978, 20047.599048018958, 20579.279725209697, 20676.48308792277, 21039.47401314964, 21211.575768484545, 21218.25736099229, 14528.636784080376, 15660.686786264325, 15732.44936427231, 15838.841611583852, 15847.92076039624, 3544.6987006104514, 3563.750537462379, 3579.319929213441, 3585.659362360572, 3586.211033572636, 118950.0, 120516.0, 122184.0, 124087.0, 126698.36650816687, 15622.453214137679, 16269.349226031063, 16411.83588164127, 16585.25366358517, 16608.83391166097, 29700.257493562833, 29717.702822971925, 30335.0, 30415.695843041285, 30467.390652186834, 8376.204260595234, 8530.10433904431, 8557.48655080697, 8567.357448191286, 8596.8710469343, 4019.8593049243136, 4090.1206240658403, 4098.672106231495, 4099.85650502231, 4151.896202594899, 5236.816711415082, 5295.761690723926, 5298.311219541512, 5352.116900711417, 5357.249985002103, 994.3288280410694, 1018.2414101494434, 1028.7325295423243, 1033.6330602635956, 1034.6740776655329, 640.7757284950324, 652.4584594786317, 657.7270432770442, 660.4452260101538, 666.3835951744617, 10606.204534659932, 10954.616588419367, 11069.948354906272, 11203.271787333797, 11780.293182408912, 670.3196255800367, 670.7596627264959, 675.7398401615316, 685.9451243900476, 686.9969844027776, 4571.771411429415, 4622.607078398374, 4631.305304204397, 4632.0, 4653.278741795028, 98267.52598711025, 101318.0, 101514.0, 105416.0, 105749.0, 88041.55979220191, 90751.0, 90945.0, 91156.0, 93956.53021734848, 78510.6074469624, 80879.59560202279, 81235.59382203051, 82318.58840705878, 82794.0, 8859.3798434109, 8911.331650126396, 8972.820543589221, 9009.594568244314, 9089.409188402733, 35215.64784352226, 35317.0, 35389.0, 35562.64437355593, 36634.0, 44499.4227280216, 44703.10593788041, 45375.092498150516, 46135.53864461312, 46164.76917615525, 13788.103773254707, 13890.86109138936, 13976.720465590428, 14088.15471071739, 14114.223717695626, 15930.761038584198, 15936.28286727077, 16002.119883406524, 16020.11889346097, 16227.350905964102, 14337.211453370164, 14398.640033999234, 14493.275336232935, 14510.564683059103, 14597.27013649292, 14486.0, 14638.194899280641, 14661.92669036648, 14783.926080369529, 14790.852091478948, 17177.914110429367, 17563.736543952105, 18187.363442279457, 18202.45392638223, 18362.0, 13630.318484075955, 13679.726405472034, 13790.793138103138, 13945.651358715708, 13953.093048951785, 13707.931460342634, 13803.2408217549, 13842.792358114837, 14061.789073163705, 14098.36557354942], "value": 8576.657849906289, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1546.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 107.0, 101.0, 107.0, 107.0, 108.0, 110.0, 101.0, 107.0, 111.0], "value": 105.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 133.0, 144.0, 190.0, 118.0, 147.0, 156.0, 125.0, 165.0, 119.0], "value": 145.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 166.0, 162.0, 165.0, 163.0, 164.0, 165.0, 163.0, 167.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 76.0, 76.0, 165.0, 83.0, 74.0, 74.0, 75.0, 146.0, 76.0], "value": 92.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 130.0, 89.0, 84.0, 79.0, 124.0, 78.0, 87.0, 128.0], "value": 96.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 116.0, 118.0, 114.0, 114.0, 114.0, 114.0, 117.0, 114.0], "value": 114.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 151.0, 151.0, 152.0, 149.0, 155.0, 152.0, 150.0, 150.0], "value": 151.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 101.0, 94.0, 96.0, 94.0, 96.0, 94.0, 97.0, 94.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 69.0, 58.0, 60.0, 61.0, 57.0, 56.0, 62.0, 56.0, 61.0], "value": 59.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 60.0, 54.0, 54.0, 54.0, 54.0, 54.0, 58.0], "value": 55.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 88.0, 89.0, 77.0, 76.0, 92.0, 86.0, 76.0, 78.0, 78.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 141.0, 129.0, 129.0, 124.0, 141.0, 131.0, 136.0, 135.0, 168.0], "value": 136.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 182.0, 175.0, 188.0, 179.0, 183.0, 171.0, 182.0, 184.0, 180.0], "value": 180.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 66.0, 70.0, 65.0, 71.0, 68.0, 66.0, 67.0, 66.0], "value": 67.1, "unit": "score"}]}]} 16:54:10 INFO - Return code: 0 16:54:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:54:10 INFO - # TBPL SUCCESS # 16:54:10 INFO - Running post-action listener: _package_coverage_data 16:54:10 INFO - Running post-action listener: _resource_record_post_action 16:54:10 INFO - [mozharness: 2017-08-02 23:54:10.727887Z] Finished run-tests step (success) 16:54:10 INFO - Running post-run listener: _resource_record_post_run 16:54:10 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 433307648; Read time: 12; Write time: 260740 16:54:10 INFO - TinderboxPrint: CPU usage
13.2% 16:54:10 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:54:10 INFO - TinderboxPrint: I/O write bytes / time
433,307,648 / 260,740 16:54:10 INFO - TinderboxPrint: CPU idle
8,271.9 (86.8%) 16:54:10 INFO - TinderboxPrint: CPU user
1,205.3 (12.6%) 16:54:10 INFO - TinderboxPrint: Swap in / out
0 / 0 16:54:10 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 107728896; Read time: 0; Write time: 82828 16:54:10 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:54:10 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 0; Write bytes: 325578752; Read time: 0; Write time: 177912 16:54:11 INFO - Running post-run listener: _upload_blobber_files 16:54:11 INFO - Blob upload gear active. 16:54:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:54:11 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 16:54:11 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'] 16:54:11 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 16:54:48 INFO - (blobuploader) - INFO - Open directory for files ... 16:54:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:54:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:54:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:55:47 INFO - (blobuploader) - CRITICAL - Something went wrong on blobber! 16:55:47 INFO - (blobuploader) - INFO - Upload failed. Trying again ... 16:55:47 INFO - (blobuploader) - INFO - Done attempting. 16:55:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:55:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:55:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:56:47 INFO - (blobuploader) - CRITICAL - Something went wrong on blobber! 16:56:47 INFO - (blobuploader) - INFO - Upload failed. Trying again ... 16:56:47 INFO - (blobuploader) - INFO - Done attempting. 16:56:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:56:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:56:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:57:47 INFO - (blobuploader) - CRITICAL - Something went wrong on blobber! 16:57:47 INFO - (blobuploader) - INFO - Upload failed. Trying again ... 16:57:47 INFO - (blobuploader) - INFO - Done attempting. 16:57:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:57:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:57:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:58:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:58:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:58:15 INFO - (blobuploader) - INFO - Done attempting. 16:58:15 INFO - (blobuploader) - INFO - Iteration through files over. 16:58:15 INFO - Return code: 0 16:58:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:58:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:58:15 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": null, "perfherder-data.json": null, "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39cebd63e67dcf86821852dc40d11f639ecb4ccbc0859d0b4d7c54a38515b4c91327be70b09b0b9be9ddf29008e67a0d44d53a0774420dcb272b9fcf9d21b856", "resource-usage.json": null} 16:58:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:58:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:58:15 INFO - Contents: 16:58:15 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": null, "perfherder-data.json": null, "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39cebd63e67dcf86821852dc40d11f639ecb4ccbc0859d0b4d7c54a38515b4c91327be70b09b0b9be9ddf29008e67a0d44d53a0774420dcb272b9fcf9d21b856", "resource-usage.json": null} 16:58:15 INFO - Running post-run listener: copy_logs_to_upload_dir 16:58:15 INFO - Copying logs to upload dir... 16:58:15 INFO - mkdir: /builds/slave/test/build/upload/logs 16:58:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1491.601174 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 24 mins, 51 secs) (at 2017-08-02 16:58:15.388652) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-02 16:58:15.394974) ========= 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=483e410453545d07cca6935c000001ab-1501703203.267995-960522969 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": null, "perfherder-data.json": null, "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39cebd63e67dcf86821852dc40d11f639ecb4ccbc0859d0b4d7c54a38515b4c91327be70b09b0b9be9ddf29008e67a0d44d53a0774420dcb272b9fcf9d21b856", "resource-usage.json": null} build_url:https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011213 build_url: 'https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": null, "perfherder-data.json": null, "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39cebd63e67dcf86821852dc40d11f639ecb4ccbc0859d0b4d7c54a38515b4c91327be70b09b0b9be9ddf29008e67a0d44d53a0774420dcb272b9fcf9d21b856", "resource-usage.json": null}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-02 16:58:15.419865) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 16:58:15.420212) ========= 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=483e410453545d07cca6935c000001ab-1501703203.267995-960522969 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004361 ========= master_lag: 0.09 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 16:58:15.510218) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-02 16:58:15.510822) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-02 16:58:15.511148) ========= ========= Total master_lag: 0.25 =========