builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-013 starttime: 1501716864.55 results: success (0) revision: 4c7317211990d6cf156c103a73a5b3ec41f2dd4d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-02 16:34:24.550295) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-02 16:34:24.550968) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-02 16:34:24.551239) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-02 16:34:24.593409) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 16:34:24.593759) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-02 16:34:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 216M=0s 2017-08-02 16:34:24 (216 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.189180 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 16:34:24.818448) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 16:34:24.818760) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016589 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 16:34:24.868164) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 16:34:24.871511) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-02 16:34:24,955 truncating revision to first 12 chars 2017-08-02 16:34:24,955 Setting DEBUG logging. 2017-08-02 16:34:24,955 attempt 1/10 2017-08-02 16:34:24,955 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:34:25,424 unpacking tar archive at: mozilla-central-4c7317211990/testing/mozharness/ program finished with exit code 0 elapsedTime=0.864826 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 16:34:25.749306) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-02 16:34:25.752634) ========= script_repo_revision: 4c7317211990d6cf156c103a73a5b3ec41f2dd4d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-02 16:34:25.753010) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-02 16:34:25.753270) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-02 16:34:25.761851) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 52 secs) (at 2017-08-02 16:34:25.762112) ========= /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=9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:34:26 INFO - MultiFileLogger online at 20170802 16:34:26 in /builds/slave/test 16:34:26 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:34:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:34:26 INFO - {'append_to_log': False, 16:34:26 INFO - 'base_work_dir': '/builds/slave/test', 16:34:26 INFO - 'blob_upload_branch': 'Firefox', 16:34:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:34:26 INFO - 'branch': 'Firefox', 16:34:26 INFO - 'buildbot_json_path': 'buildprops.json', 16:34:26 INFO - 'code_coverage': False, 16:34:26 INFO - 'config_files': ('talos/linux_config.py',), 16:34:26 INFO - 'default_actions': ('clobber', 16:34:26 INFO - 'read-buildbot-config', 16:34:26 INFO - 'download-and-extract', 16:34:26 INFO - 'populate-webroot', 16:34:26 INFO - 'create-virtualenv', 16:34:26 INFO - 'install', 16:34:26 INFO - 'setup-mitmproxy', 16:34:26 INFO - 'run-tests'), 16:34:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:34:26 INFO - 'disable_ccov_upload': False, 16:34:26 INFO - 'download_minidump_stackwalk': True, 16:34:26 INFO - 'download_symbols': 'ondemand', 16:34:26 INFO - 'enable_stylo': False, 16:34:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:34:26 INFO - 'tooltool.py': '/tools/tooltool.py', 16:34:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:34:26 INFO - '/tools/misc-python/virtualenv.py')}, 16:34:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:34:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:34:26 INFO - 'gecko_profile': False, 16:34:26 INFO - 'gecko_profile_interval': 0, 16:34:26 INFO - 'installer_path': 'installer.exe', 16:34:26 INFO - 'log_level': 'info', 16:34:26 INFO - 'log_name': 'talos', 16:34:26 INFO - 'log_to_console': True, 16:34:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:34:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:34:26 INFO - 'opt_config_files': (), 16:34:26 INFO - 'pip_index': False, 16:34:26 INFO - 'suite': 'dromaeojs-e10s', 16:34:26 INFO - 'system_bits': '32', 16:34:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:34:26 INFO - 'title': 'talos-linux64-ix-013', 16:34:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:34:26 INFO - 'use_talos_json': True, 16:34:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:34:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:34:26 INFO - 'work_dir': 'build'} 16:34:26 INFO - [mozharness: 2017-08-02 23:34:26.064664Z] Running clobber step. 16:34:26 INFO - Running pre-action listener: _resource_record_pre_action 16:34:26 INFO - Running main action method: clobber 16:34:26 INFO - rmtree: /builds/slave/test/build 16:34:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:34:27 INFO - Running post-action listener: _resource_record_post_action 16:34:27 INFO - [mozharness: 2017-08-02 23:34:27.053213Z] Finished clobber step (success) 16:34:27 INFO - [mozharness: 2017-08-02 23:34:27.053315Z] Running read-buildbot-config step. 16:34:27 INFO - Running pre-action listener: _resource_record_pre_action 16:34:27 INFO - Running main action method: read_buildbot_config 16:34:27 INFO - Using buildbot properties: 16:34:27 INFO - { 16:34:27 INFO - "project": "", 16:34:27 INFO - "product": "firefox", 16:34:27 INFO - "who": "kwierso@gmail.com", 16:34:27 INFO - "installer_path": "public/build/target.tar.bz2", 16:34:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 16:34:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:34:27 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 16:34:27 INFO - "stage_platform": "linux64-stylo", 16:34:27 INFO - "basedir": "/builds/slave/test", 16:34:27 INFO - "buildnumber": 61, 16:34:27 INFO - "platform": "ubuntu64_hw_stylo", 16:34:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:34:27 INFO - "slavebuilddir": "test", 16:34:27 INFO - "taskId": "af8FhuKsSfilzxRGdelKeA", 16:34:27 INFO - "branch": "mozilla-central", 16:34:27 INFO - "script_repo_revision": "production", 16:34:27 INFO - "revision": "4c7317211990d6cf156c103a73a5b3ec41f2dd4d", 16:34:27 INFO - "slavename": "talos-linux64-ix-013", 16:34:27 INFO - "repo_path": "mozilla-central" 16:34:27 INFO - } 16:34:27 INFO - Finding installer, test and symbols from parent task. 16:34:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/af8FhuKsSfilzxRGdelKeA'}, attempt #1 16:34:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/af8FhuKsSfilzxRGdelKeA'}, attempt #1 16:34:27 INFO - Task dependencies: exfe1HmBRcWAzUMI2Oc6Pg 16:34:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg'}, attempt #1 16:34:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.tar.bz2 16:34:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.test_packages.json 16:34:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.crashreporter-symbols.zip 16:34:28 INFO - Running post-action listener: _resource_record_post_action 16:34:28 INFO - [mozharness: 2017-08-02 23:34:28.447123Z] Finished read-buildbot-config step (success) 16:34:28 INFO - [mozharness: 2017-08-02 23:34:28.447344Z] Running download-and-extract step. 16:34:28 INFO - Running pre-action listener: _resource_record_pre_action 16:34:28 INFO - Running main action method: download_and_extract 16:34:28 INFO - mkdir: /builds/slave/test/build/tests 16:34:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:34:28 INFO - trying https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.test_packages.json 16:34:28 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:34:28 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:34:28 INFO - Downloaded 989 bytes. 16:34:28 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:34:28 INFO - Using the following test package requirements: 16:34:28 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:34:28 INFO - u'common': [u'target.common.tests.zip'], 16:34:28 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:34:28 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:34:28 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:34:28 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:34:28 INFO - u'mozbase': [u'target.common.tests.zip'], 16:34:28 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:34:28 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:34:28 INFO - u'web-platform': [u'target.common.tests.zip', 16:34:28 INFO - u'target.web-platform.tests.tar.gz'], 16:34:28 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:34:28 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:34:28 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:34:28 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:34:28 INFO - Fetch https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.common.tests.zip into memory 16:34:30 INFO - Content-Length response header: 72348219 16:34:30 INFO - Bytes received: 72348219 16:34:41 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:34:41 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:34:41 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:34:41 INFO - Fetch https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.common.tests.zip into memory 16:34:43 INFO - Content-Length response header: 72348219 16:34:43 INFO - Bytes received: 72348219 16:34:56 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:34:56 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:34:56 INFO - Fetch https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.talos.tests.zip into memory 16:34:56 INFO - Content-Length response header: 14496464 16:34:56 INFO - Bytes received: 14496464 16:34:57 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:57 INFO - trying https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.tar.bz2 16:34:57 INFO - Downloading https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:34:57 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:34:58 INFO - Downloaded 62191359 bytes. 16:34:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.tar.bz2 16:34:58 INFO - mkdir: /builds/slave/test/properties 16:34:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:34:58 INFO - Writing to file /builds/slave/test/properties/build_url 16:34:58 INFO - Contents: 16:34:58 INFO - build_url:https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.tar.bz2 16:34:58 INFO - Running post-action listener: _resource_record_post_action 16:34:58 INFO - Running post-action listener: set_extra_try_arguments 16:34:58 INFO - [mozharness: 2017-08-02 23:34:58.258246Z] Finished download-and-extract step (success) 16:34:58 INFO - [mozharness: 2017-08-02 23:34:58.258344Z] Running populate-webroot step. 16:34:58 INFO - Running pre-action listener: _resource_record_pre_action 16:34:58 INFO - Running main action method: populate_webroot 16:34:58 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:34:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:34:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:34:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:34:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:34:58 INFO - 'tests': ['damp', 'tps']}, 16:34:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:34:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:34:58 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:34:58 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:34:58 INFO - 'other-e10s': {'tests': ['a11yr', 16:34:58 INFO - 'ts_paint', 16:34:58 INFO - 'tpaint', 16:34:58 INFO - 'sessionrestore', 16:34:58 INFO - 'sessionrestore_many_windows', 16:34:58 INFO - 'sessionrestore_no_auto_restore', 16:34:58 INFO - 'tabpaint']}, 16:34:58 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:34:58 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:34:58 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:34:58 INFO - 'talos_options': ['--mitmproxy', 16:34:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:34:58 INFO - '--firstNonBlankPaint'], 16:34:58 INFO - 'tests': ['quantum_pageload_google', 16:34:58 INFO - 'quantum_pageload_youtube', 16:34:58 INFO - 'quantum_pageload_amazon', 16:34:58 INFO - 'quantum_pageload_facebook']}, 16:34:58 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:34:58 INFO - 'talos_options': ['--stylo', 16:34:58 INFO - '--mitmproxy', 16:34:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:34:58 INFO - '--firstNonBlankPaint'], 16:34:58 INFO - 'tests': ['quantum_pageload_google', 16:34:58 INFO - 'quantum_pageload_youtube', 16:34:58 INFO - 'quantum_pageload_amazon', 16:34:58 INFO - 'quantum_pageload_facebook']}, 16:34:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:34:58 INFO - 'tsvgr_opacity', 16:34:58 INFO - 'tart', 16:34:58 INFO - 'tscrollx', 16:34:58 INFO - 'cart', 16:34:58 INFO - 'tsvg_static']}, 16:34:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:34:58 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:34:58 INFO - 'talos_options': ['--stylo', 16:34:58 INFO - '--stylo-threads=1', 16:34:58 INFO - '--mitmproxy', 16:34:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:34:58 INFO - '--firstNonBlankPaint'], 16:34:58 INFO - 'tests': ['tp6_google', 16:34:58 INFO - 'tp6_youtube', 16:34:58 INFO - 'tp6_amazon', 16:34:58 INFO - 'tp6_facebook']}, 16:34:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:34:58 INFO - 'talos_options': ['--xperf_path', 16:34:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:34:58 INFO - 'tests': ['tp5n']}}} 16:34:58 INFO - Running post-action listener: _resource_record_post_action 16:34:58 INFO - [mozharness: 2017-08-02 23:34:58.266968Z] Finished populate-webroot step (success) 16:34:58 INFO - [mozharness: 2017-08-02 23:34:58.267058Z] Running create-virtualenv step. 16:34:58 INFO - Running pre-action listener: _resource_record_pre_action 16:34:58 INFO - Running main action method: create_virtualenv 16:34:58 INFO - Creating virtualenv /builds/slave/test/build/venv 16:34:58 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:34:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:34:58 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:34:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:34:58 INFO - Using real prefix '/usr' 16:34:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:34:58 INFO - Installing distribute.............................................................................................................................................................................................done. 16:35:00 INFO - Installing pip.................done. 16:35:00 INFO - Return code: 0 16:35:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:35:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:35:01 INFO - Reading from file tmpfile_stdout 16:35:01 INFO - Output received: 16:35:01 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:35:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:35: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:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35: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:35:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd7d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd4eb0>, '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 0xdd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd6240>, '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': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', '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:35: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', 'pip>=1.5'] in /builds/slave/test/build 16:35: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 pip>=1.5 16:35:01 INFO - Using env: {'DISPLAY': ':0', 16:35:01 INFO - 'HOME': '/home/cltbld', 16:35:01 INFO - 'LANG': 'en_US.UTF-8', 16:35:01 INFO - 'LANGUAGE': 'en_US:en', 16:35:01 INFO - 'LOGNAME': 'cltbld', 16:35:01 INFO - 'MAIL': '/var/mail/cltbld', 16:35:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:01 INFO - 'MOZ_NO_REMOTE': '1', 16:35:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:35:01 INFO - 'NO_EM_RESTART': '1', 16:35:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:35:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:01 INFO - 'PWD': '/builds/slave/test', 16:35:01 INFO - 'SHELL': '/bin/bash', 16:35:01 INFO - 'SHLVL': '1', 16:35:01 INFO - 'TERM': 'linux', 16:35:01 INFO - 'TMOUT': '86400', 16:35:01 INFO - 'USER': 'cltbld', 16:35:01 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', 16:35:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:01 INFO - '_': '/tools/buildbot/bin/python'} 16:35:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:01 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:35:01 INFO - Cleaning up... 16:35:01 INFO - Return code: 0 16:35:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:35: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:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35: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:35:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35: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', '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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd7d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd4eb0>, '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 0xdd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd6240>, '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': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', '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:35: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', 'psutil>=3.1.1'] in /builds/slave/test/build 16:35: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 psutil>=3.1.1 16:35:01 INFO - Using env: {'DISPLAY': ':0', 16:35:01 INFO - 'HOME': '/home/cltbld', 16:35:01 INFO - 'LANG': 'en_US.UTF-8', 16:35:01 INFO - 'LANGUAGE': 'en_US:en', 16:35:01 INFO - 'LOGNAME': 'cltbld', 16:35:01 INFO - 'MAIL': '/var/mail/cltbld', 16:35:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:01 INFO - 'MOZ_NO_REMOTE': '1', 16:35:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:35:01 INFO - 'NO_EM_RESTART': '1', 16:35:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:35:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:01 INFO - 'PWD': '/builds/slave/test', 16:35:01 INFO - 'SHELL': '/bin/bash', 16:35:01 INFO - 'SHLVL': '1', 16:35:01 INFO - 'TERM': 'linux', 16:35:01 INFO - 'TMOUT': '86400', 16:35:01 INFO - 'USER': 'cltbld', 16:35:01 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', 16:35:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:01 INFO - '_': '/tools/buildbot/bin/python'} 16:35:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:01 INFO - Downloading/unpacking psutil>=3.1.1 16:35:01 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:35:01 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:35:01 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:35:01 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:35:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:35:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:35:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:35:02 INFO - Installing collected packages: psutil 16:35:02 INFO - Running setup.py install for psutil 16:35:03 INFO - building 'psutil._psutil_linux' extension 16:35:03 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:35:03 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:35:04 INFO - building 'psutil._psutil_posix' extension 16:35:04 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:35:04 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:35:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:35:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:35:04 INFO - Successfully installed psutil 16:35:04 INFO - Cleaning up... 16:35:04 INFO - Return code: 0 16:35:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:35:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:04 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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd7d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd4eb0>, '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 0xdd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd6240>, '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': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', '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:35:04 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:35:04 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:35:04 INFO - Using env: {'DISPLAY': ':0', 16:35:04 INFO - 'HOME': '/home/cltbld', 16:35:04 INFO - 'LANG': 'en_US.UTF-8', 16:35:04 INFO - 'LANGUAGE': 'en_US:en', 16:35:04 INFO - 'LOGNAME': 'cltbld', 16:35:04 INFO - 'MAIL': '/var/mail/cltbld', 16:35:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:04 INFO - 'MOZ_NO_REMOTE': '1', 16:35:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:35:04 INFO - 'NO_EM_RESTART': '1', 16:35:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:35:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:04 INFO - 'PWD': '/builds/slave/test', 16:35:04 INFO - 'SHELL': '/bin/bash', 16:35:04 INFO - 'SHLVL': '1', 16:35:04 INFO - 'TERM': 'linux', 16:35:04 INFO - 'TMOUT': '86400', 16:35:04 INFO - 'USER': 'cltbld', 16:35:04 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', 16:35:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:04 INFO - '_': '/tools/buildbot/bin/python'} 16:35:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:35: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:35: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:35: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:35: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:35:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:35:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:35:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:35:05 INFO - Installing collected packages: mozsystemmonitor 16:35:05 INFO - Running setup.py install for mozsystemmonitor 16:35:05 INFO - Successfully installed mozsystemmonitor 16:35:05 INFO - Cleaning up... 16:35:05 INFO - Return code: 0 16:35:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:05 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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd7d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd4eb0>, '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 0xdd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd6240>, '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': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', '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:35:05 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:35:05 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:35:05 INFO - Using env: {'DISPLAY': ':0', 16:35:05 INFO - 'HOME': '/home/cltbld', 16:35:05 INFO - 'LANG': 'en_US.UTF-8', 16:35:05 INFO - 'LANGUAGE': 'en_US:en', 16:35:05 INFO - 'LOGNAME': 'cltbld', 16:35:05 INFO - 'MAIL': '/var/mail/cltbld', 16:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:05 INFO - 'MOZ_NO_REMOTE': '1', 16:35:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:35:05 INFO - 'NO_EM_RESTART': '1', 16:35:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:35:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:05 INFO - 'PWD': '/builds/slave/test', 16:35:05 INFO - 'SHELL': '/bin/bash', 16:35:05 INFO - 'SHLVL': '1', 16:35:05 INFO - 'TERM': 'linux', 16:35:05 INFO - 'TMOUT': '86400', 16:35:05 INFO - 'USER': 'cltbld', 16:35:05 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', 16:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:05 INFO - '_': '/tools/buildbot/bin/python'} 16:35:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:06 INFO - Downloading/unpacking jsonschema==2.5.1 16:35:06 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:35:06 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:35:06 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:35:06 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:35:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:35:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:35:07 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:35:07 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:35:07 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:35:07 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:35:07 INFO - Downloading functools32-3.2.3-2.tar.gz 16:35:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:35:07 INFO - warning: no files found matching '*.txt' 16:35:07 INFO - no previously-included directories found matching 'build' 16:35:07 INFO - no previously-included directories found matching 'dist' 16:35:07 INFO - no previously-included directories found matching '.git*' 16:35:07 INFO - Installing collected packages: jsonschema, functools32 16:35:07 INFO - Running setup.py install for functools32 16:35:07 INFO - warning: no files found matching '*.txt' 16:35:07 INFO - no previously-included directories found matching 'build' 16:35:07 INFO - no previously-included directories found matching 'dist' 16:35:07 INFO - no previously-included directories found matching '.git*' 16:35:07 INFO - Successfully installed jsonschema functools32 16:35:07 INFO - Cleaning up... 16:35:07 INFO - Return code: 0 16:35:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:35:07 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:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:07 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:35:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:07 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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd7d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd4eb0>, '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 0xdd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd6240>, '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': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', '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:35:07 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:35:07 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:35:07 INFO - Using env: {'DISPLAY': ':0', 16:35:07 INFO - 'HOME': '/home/cltbld', 16:35:07 INFO - 'LANG': 'en_US.UTF-8', 16:35:07 INFO - 'LANGUAGE': 'en_US:en', 16:35:07 INFO - 'LOGNAME': 'cltbld', 16:35:07 INFO - 'MAIL': '/var/mail/cltbld', 16:35:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:07 INFO - 'MOZ_NO_REMOTE': '1', 16:35:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:35:07 INFO - 'NO_EM_RESTART': '1', 16:35:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:35:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:07 INFO - 'PWD': '/builds/slave/test', 16:35:07 INFO - 'SHELL': '/bin/bash', 16:35:07 INFO - 'SHLVL': '1', 16:35:07 INFO - 'TERM': 'linux', 16:35:07 INFO - 'TMOUT': '86400', 16:35:07 INFO - 'USER': 'cltbld', 16:35:07 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', 16:35:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:07 INFO - '_': '/tools/buildbot/bin/python'} 16:35:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:35:07 INFO - Cleaning up... 16:35:07 INFO - Return code: 0 16:35:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:35:07 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:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:07 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:35:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:07 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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd7d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd4eb0>, '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 0xdd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd6240>, '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': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', '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:35:07 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:35:07 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:35:07 INFO - Using env: {'DISPLAY': ':0', 16:35:07 INFO - 'HOME': '/home/cltbld', 16:35:07 INFO - 'LANG': 'en_US.UTF-8', 16:35:07 INFO - 'LANGUAGE': 'en_US:en', 16:35:07 INFO - 'LOGNAME': 'cltbld', 16:35:07 INFO - 'MAIL': '/var/mail/cltbld', 16:35:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:07 INFO - 'MOZ_NO_REMOTE': '1', 16:35:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:35:07 INFO - 'NO_EM_RESTART': '1', 16:35:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:35:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:07 INFO - 'PWD': '/builds/slave/test', 16:35:07 INFO - 'SHELL': '/bin/bash', 16:35:07 INFO - 'SHLVL': '1', 16:35:07 INFO - 'TERM': 'linux', 16:35:07 INFO - 'TMOUT': '86400', 16:35:07 INFO - 'USER': 'cltbld', 16:35:07 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', 16:35:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:07 INFO - '_': '/tools/buildbot/bin/python'} 16:35:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:07 INFO - Downloading/unpacking blobuploader==1.2.4 16:35:07 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:35:07 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:35:07 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:35:07 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:35:09 INFO - Downloading blobuploader-1.2.4.tar.gz 16:35:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:35:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:35:09 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:35:09 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:35:09 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:35:09 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:35:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:35:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:35:09 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:35:09 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:35:09 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:35:09 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:35:09 INFO - Downloading docopt-0.6.1.tar.gz 16:35:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:35:09 INFO - Installing collected packages: blobuploader, requests, docopt 16:35:09 INFO - Running setup.py install for blobuploader 16:35:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:35:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:35:09 INFO - Running setup.py install for requests 16:35:10 INFO - Running setup.py install for docopt 16:35:10 INFO - Successfully installed blobuploader requests docopt 16:35:10 INFO - Cleaning up... 16:35:10 INFO - Return code: 0 16:35:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:35:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:10 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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd7d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd4eb0>, '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 0xdd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd6240>, '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': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', '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:35:10 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:35:10 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:35:10 INFO - Using env: {'DISPLAY': ':0', 16:35:10 INFO - 'HOME': '/home/cltbld', 16:35:10 INFO - 'LANG': 'en_US.UTF-8', 16:35:10 INFO - 'LANGUAGE': 'en_US:en', 16:35:10 INFO - 'LOGNAME': 'cltbld', 16:35:10 INFO - 'MAIL': '/var/mail/cltbld', 16:35:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:10 INFO - 'MOZ_NO_REMOTE': '1', 16:35:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:35:10 INFO - 'NO_EM_RESTART': '1', 16:35:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:35:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:10 INFO - 'PWD': '/builds/slave/test', 16:35:10 INFO - 'SHELL': '/bin/bash', 16:35:10 INFO - 'SHLVL': '1', 16:35:10 INFO - 'TERM': 'linux', 16:35:10 INFO - 'TMOUT': '86400', 16:35:10 INFO - 'USER': 'cltbld', 16:35:10 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', 16:35:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:10 INFO - '_': '/tools/buildbot/bin/python'} 16:35:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:35:10 INFO - Running setup.py (path:/tmp/pip-q_zfQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:35:10 INFO - Running setup.py (path:/tmp/pip-7FmyMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:35:10 INFO - Running setup.py (path:/tmp/pip-nEVZ_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:35:10 INFO - Running setup.py (path:/tmp/pip-5BAV_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:35:10 INFO - Running setup.py (path:/tmp/pip-dqU7_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:35:10 INFO - Running setup.py (path:/tmp/pip-G0SQuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:35:10 INFO - Running setup.py (path:/tmp/pip-RrqwJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:35:10 INFO - Running setup.py (path:/tmp/pip-0VRwOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:35:11 INFO - Running setup.py (path:/tmp/pip-1UTaYW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:35:11 INFO - Running setup.py (path:/tmp/pip-GACKdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:35:11 INFO - Running setup.py (path:/tmp/pip-WijQbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:35:11 INFO - Running setup.py (path:/tmp/pip-0FyhQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:35:11 INFO - Running setup.py (path:/tmp/pip-09GqgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:35:11 INFO - Running setup.py (path:/tmp/pip-NnO39x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:35:11 INFO - Running setup.py (path:/tmp/pip-lvnK67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:35:11 INFO - Running setup.py (path:/tmp/pip-xMhEcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:35:11 INFO - Running setup.py (path:/tmp/pip-iSCRyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:35:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:35:11 INFO - Running setup.py install for manifestparser 16:35:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:35:11 INFO - Running setup.py install for mozcrash 16:35:11 INFO - Running setup.py install for mozdebug 16:35:11 INFO - Running setup.py install for mozdevice 16:35:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:35:12 INFO - Running setup.py install for mozfile 16:35:12 INFO - Running setup.py install for mozhttpd 16:35:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:35:12 INFO - Running setup.py install for mozinfo 16:35:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:35:12 INFO - Running setup.py install for mozInstall 16:35:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:35:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:35:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:35:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:35:12 INFO - Running setup.py install for mozleak 16:35:12 INFO - Running setup.py install for mozlog 16:35:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:35:12 INFO - Running setup.py install for moznetwork 16:35:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:35:12 INFO - Running setup.py install for mozprocess 16:35:12 INFO - Running setup.py install for mozprofile 16:35:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:35:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:35:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:35:13 INFO - Running setup.py install for mozrunner 16:35:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:35:13 INFO - Running setup.py install for mozscreenshot 16:35:13 INFO - Running setup.py install for moztest 16:35:13 INFO - Running setup.py install for mozversion 16:35:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:35:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:35:13 INFO - Cleaning up... 16:35:13 INFO - Return code: 0 16:35:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:35:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:13 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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd7d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd4eb0>, '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 0xdd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd6240>, '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': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', '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:35:13 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:35:13 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:35:13 INFO - Using env: {'DISPLAY': ':0', 16:35:13 INFO - 'HOME': '/home/cltbld', 16:35:13 INFO - 'LANG': 'en_US.UTF-8', 16:35:13 INFO - 'LANGUAGE': 'en_US:en', 16:35:13 INFO - 'LOGNAME': 'cltbld', 16:35:13 INFO - 'MAIL': '/var/mail/cltbld', 16:35:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:13 INFO - 'MOZ_NO_REMOTE': '1', 16:35:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:35:13 INFO - 'NO_EM_RESTART': '1', 16:35:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:35:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:13 INFO - 'PWD': '/builds/slave/test', 16:35:13 INFO - 'SHELL': '/bin/bash', 16:35:13 INFO - 'SHLVL': '1', 16:35:13 INFO - 'TERM': 'linux', 16:35:13 INFO - 'TMOUT': '86400', 16:35:13 INFO - 'USER': 'cltbld', 16:35:13 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', 16:35:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:13 INFO - '_': '/tools/buildbot/bin/python'} 16:35:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:35:13 INFO - Running setup.py (path:/tmp/pip-PvmHUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:35:13 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:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:35:13 INFO - Running setup.py (path:/tmp/pip-F8FzkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:35:13 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:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:35:13 INFO - Running setup.py (path:/tmp/pip-eCrSmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:35:13 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:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:35:13 INFO - Running setup.py (path:/tmp/pip-rmlOts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:35:13 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:35:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:35:13 INFO - Running setup.py (path:/tmp/pip-c_fMS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:35:14 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:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:35:14 INFO - Running setup.py (path:/tmp/pip-FMSKB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:35:14 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:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:35:14 INFO - Running setup.py (path:/tmp/pip-TwRVxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:35:14 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:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:35:14 INFO - Running setup.py (path:/tmp/pip-zQ8nrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:35:14 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:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:35:14 INFO - Running setup.py (path:/tmp/pip-5UfvvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:35:14 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:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:35:14 INFO - Running setup.py (path:/tmp/pip-4z_8BF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:35:14 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:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:35:14 INFO - Running setup.py (path:/tmp/pip-csk676-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:35:14 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:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:35:14 INFO - Running setup.py (path:/tmp/pip-3Hcfh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:35:14 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:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:35:14 INFO - Running setup.py (path:/tmp/pip-P93ZPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:35:14 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:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:35:14 INFO - Running setup.py (path:/tmp/pip-K2bNSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:35:14 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:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:35:14 INFO - Running setup.py (path:/tmp/pip-2P6tH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:35:14 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:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:35:14 INFO - Running setup.py (path:/tmp/pip-iOKQP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:35:14 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:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:35:14 INFO - Running setup.py (path:/tmp/pip-5m5TsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:35:14 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:35:14 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:35:14 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:35:14 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:35:14 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:35:14 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:35:14 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:35:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:35:14 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:35:14 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:35:14 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:35:14 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:35:16 INFO - Downloading blessings-1.6.tar.gz 16:35:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:35:16 INFO - Installing collected packages: blessings 16:35:16 INFO - Running setup.py install for blessings 16:35:16 INFO - Successfully installed blessings 16:35:16 INFO - Cleaning up... 16:35:16 INFO - Return code: 0 16:35:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:35:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:35:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:35:16 INFO - Reading from file tmpfile_stdout 16:35:16 INFO - Current package versions: 16:35:16 INFO - argparse == 1.2.1 16:35:16 INFO - blessings == 1.6 16:35:16 INFO - blobuploader == 1.2.4 16:35:16 INFO - docopt == 0.6.1 16:35:16 INFO - functools32 == 3.2.3-2 16:35:16 INFO - jsonschema == 2.5.1 16:35:16 INFO - manifestparser == 1.1 16:35:16 INFO - mozInstall == 1.13 16:35:16 INFO - mozcrash == 1.0 16:35:16 INFO - mozdebug == 0.1 16:35:16 INFO - mozdevice == 0.51 16:35:16 INFO - mozfile == 1.2 16:35:16 INFO - mozhttpd == 0.7 16:35:16 INFO - mozinfo == 0.10 16:35:16 INFO - mozleak == 0.1 16:35:16 INFO - mozlog == 3.5 16:35:16 INFO - moznetwork == 0.27 16:35:16 INFO - mozprocess == 0.25 16:35:16 INFO - mozprofile == 0.28 16:35:16 INFO - mozrunner == 6.13 16:35:16 INFO - mozscreenshot == 0.1 16:35:16 INFO - mozsystemmonitor == 0.3 16:35:16 INFO - moztest == 0.8 16:35:16 INFO - mozversion == 1.4 16:35:16 INFO - psutil == 3.1.1 16:35:16 INFO - requests == 1.2.3 16:35:16 INFO - wsgiref == 0.1.2 16:35:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:35:16 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:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:16 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:35:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:16 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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd7d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd4eb0>, '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 0xdd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd6240>, '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': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', '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:35:16 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:35:16 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:35:16 INFO - Using env: {'DISPLAY': ':0', 16:35:16 INFO - 'HOME': '/home/cltbld', 16:35:16 INFO - 'LANG': 'en_US.UTF-8', 16:35:16 INFO - 'LANGUAGE': 'en_US:en', 16:35:16 INFO - 'LOGNAME': 'cltbld', 16:35:16 INFO - 'MAIL': '/var/mail/cltbld', 16:35:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:16 INFO - 'MOZ_NO_REMOTE': '1', 16:35:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:35:16 INFO - 'NO_EM_RESTART': '1', 16:35:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:35:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:16 INFO - 'PWD': '/builds/slave/test', 16:35:16 INFO - 'SHELL': '/bin/bash', 16:35:16 INFO - 'SHLVL': '1', 16:35:16 INFO - 'TERM': 'linux', 16:35:16 INFO - 'TMOUT': '86400', 16:35:16 INFO - 'USER': 'cltbld', 16:35:16 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', 16:35:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:16 INFO - '_': '/tools/buildbot/bin/python'} 16:35:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:16 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:35:16 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:35:16 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:35:16 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:35:16 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:35:16 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:35:16 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:35:16 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:35:16 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:35:16 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:35:16 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:35:16 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:35:16 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:35:16 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:35:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:35:17 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:35:17 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:35:17 INFO - Installing collected packages: simplejson 16:35:17 INFO - Running setup.py install for simplejson 16:35:17 INFO - building 'simplejson._speedups' extension 16:35:17 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:35:18 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:35:18 INFO - Successfully installed simplejson 16:35:18 INFO - Cleaning up... 16:35:18 INFO - Return code: 0 16:35:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:35:18 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:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:18 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:35:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:18 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 0xa681f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc34a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd7d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd4eb0>, '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 0xdd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd6240>, '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': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', '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:35:18 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:35:18 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:35:18 INFO - Using env: {'DISPLAY': ':0', 16:35:18 INFO - 'HOME': '/home/cltbld', 16:35:18 INFO - 'LANG': 'en_US.UTF-8', 16:35:18 INFO - 'LANGUAGE': 'en_US:en', 16:35:18 INFO - 'LOGNAME': 'cltbld', 16:35:18 INFO - 'MAIL': '/var/mail/cltbld', 16:35:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:18 INFO - 'MOZ_NO_REMOTE': '1', 16:35:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:35:18 INFO - 'NO_EM_RESTART': '1', 16:35:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:35:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:18 INFO - 'PWD': '/builds/slave/test', 16:35:18 INFO - 'SHELL': '/bin/bash', 16:35:18 INFO - 'SHLVL': '1', 16:35:18 INFO - 'TERM': 'linux', 16:35:18 INFO - 'TMOUT': '86400', 16:35:18 INFO - 'USER': 'cltbld', 16:35:18 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', 16:35:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:18 INFO - '_': '/tools/buildbot/bin/python'} 16:35:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:35:19 INFO - Cleaning up... 16:35:19 INFO - Return code: 0 16:35:19 INFO - Running post-action listener: _resource_record_post_action 16:35:19 INFO - Running post-action listener: _start_resource_monitoring 16:35:19 INFO - Starting resource monitoring. 16:35:19 INFO - [mozharness: 2017-08-02 23:35:19.228974Z] Finished create-virtualenv step (success) 16:35:19 INFO - [mozharness: 2017-08-02 23:35:19.230046Z] Running install step. 16:35:19 INFO - Running pre-action listener: _resource_record_pre_action 16:35:19 INFO - Running main action method: install 16:35:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:35:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:35:19 INFO - Reading from file tmpfile_stdout 16:35:19 INFO - Detecting whether we're running mozinstall >=1.0... 16:35:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:35:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:35:19 INFO - Reading from file tmpfile_stdout 16:35:19 INFO - Output received: 16:35:19 INFO - Usage: mozinstall [options] installer 16:35:19 INFO - Options: 16:35:19 INFO - -h, --help show this help message and exit 16:35:19 INFO - -d DEST, --destination=DEST 16:35:19 INFO - Directory to install application into. [default: 16:35:19 INFO - "/builds/slave/test"] 16:35:19 INFO - --app=APP Application being installed. [default: firefox] 16:35:19 INFO - mkdir: /builds/slave/test/build/application 16:35:19 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:35:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:35:37 INFO - Reading from file tmpfile_stdout 16:35:37 INFO - Output received: 16:35:37 INFO - /builds/slave/test/build/application/firefox/firefox 16:35:37 INFO - Running post-action listener: _resource_record_post_action 16:35:37 INFO - [mozharness: 2017-08-02 23:35:37.592556Z] Finished install step (success) 16:35:37 INFO - [mozharness: 2017-08-02 23:35:37.592859Z] Running setup-mitmproxy step. 16:35:37 INFO - Running pre-action listener: _resource_record_pre_action 16:35:37 INFO - Running main action method: setup_mitmproxy 16:35:37 INFO - Skipping: mitmproxy is not required 16:35:37 INFO - Running post-action listener: _resource_record_post_action 16:35:37 INFO - [mozharness: 2017-08-02 23:35:37.594059Z] Finished setup-mitmproxy step (success) 16:35:37 INFO - [mozharness: 2017-08-02 23:35:37.594280Z] Running run-tests step. 16:35:37 INFO - Running pre-action listener: _resource_record_pre_action 16:35:37 INFO - Running pre-action listener: _set_gcov_prefix 16:35:37 INFO - Running main action method: run_tests 16:35:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:35:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:35:37 INFO - Python 2.7.3 16:35:37 INFO - Return code: 0 16:35:37 INFO - grabbing minidump binary from tooltool 16:35:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:37 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 0xdd4eb0>, '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 0xdd5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd6240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:35:37 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:35:37 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:35:37 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:35:37 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:35:37 INFO - Return code: 0 16:35:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:35:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:35:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:35:37 INFO - ENV: RUST_BACKTRACE is now 1 16:35:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:35:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:35:37 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-013', '--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:35:37 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-013 --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:35:37 INFO - Using env: {'DISPLAY': ':0', 16:35:37 INFO - 'HOME': '/home/cltbld', 16:35:37 INFO - 'LANG': 'en_US.UTF-8', 16:35:37 INFO - 'LANGUAGE': 'en_US:en', 16:35:37 INFO - 'LOGNAME': 'cltbld', 16:35:37 INFO - 'MAIL': '/var/mail/cltbld', 16:35:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:35:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:35:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:37 INFO - 'MOZ_NO_REMOTE': '1', 16:35:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:35:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:35:37 INFO - 'NO_EM_RESTART': '1', 16:35:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:35:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:37 INFO - 'PWD': '/builds/slave/test', 16:35:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:35:37 INFO - 'RUST_BACKTRACE': '1', 16:35:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:35:37 INFO - 'SHELL': '/bin/bash', 16:35:37 INFO - 'SHLVL': '1', 16:35:37 INFO - 'STYLO_FORCE_ENABLED': '1', 16:35:37 INFO - 'TERM': 'linux', 16:35:37 INFO - 'TMOUT': '86400', 16:35:37 INFO - 'USER': 'cltbld', 16:35:37 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284', 16:35:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:37 INFO - '_': '/tools/buildbot/bin/python'} 16:35:37 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-013', '--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:35:38 INFO - mozversion application_buildid: 20170802224818 16:35:38 INFO - mozversion application_changeset: 4c7317211990d6cf156c103a73a5b3ec41f2dd4d 16:35:38 INFO - mozversion application_display_name: Nightly 16:35:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:35:38 INFO - mozversion application_name: Firefox 16:35:38 INFO - mozversion application_remotingname: firefox 16:35:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:35:38 INFO - mozversion application_vendor: Mozilla 16:35:38 INFO - mozversion application_version: 57.0a1 16:35:38 INFO - mozversion platform_buildid: 20170802224818 16:35:38 INFO - mozversion platform_changeset: 4c7317211990d6cf156c103a73a5b3ec41f2dd4d 16:35:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:35:38 INFO - mozversion platform_version: 57.0a1 16:35:38 INFO - using testdate: 1501716938 16:35:38 INFO - actual date: 1501716938 16:35:38 INFO - starting webserver on 'localhost:51242' 16:35:38 INFO - SUITE-START | Running 2 tests 16:35:38 INFO - TEST-START | dromaeo_css 16:35:38 INFO - Initialising browser for dromaeo_css test... 16:35:38 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51242/getInfo.html -profile /tmp/tmpdMBxQ3/profile 16:35:38 INFO - TEST-INFO | started process 3345 (/builds/slave/test/build/application/firefox/firefox http://localhost:51242/getInfo.html) 16:35:46 INFO - TEST-INFO | 3345: exit 0 16:35:46 INFO - Browser initialized. 16:35:46 INFO - Running cycle 1/1 for dromaeo_css test... 16:35:46 INFO - TEST-INFO | started process 3570 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdMBxQ3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:35:47 INFO - PID 3570 | 16:35:47 INFO - PID 3570 | (/builds/slave/test/build/application/firefox/firefox:3625): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:35:47 INFO - PID 3570 | 16:35:58 INFO - PID 3570 | RSS: Main: 174198784 16:35:58 INFO - PID 3570 | 16:38:56 INFO - PID 3570 | Cycle 1(1): loaded http://localhost:51242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51242/tests/dromaeo/cssquery-ext.html) 16:38:58 INFO - PID 3570 | RSS: Main: 176267264 16:38:58 INFO - PID 3570 | 16:42:00 INFO - PID 3570 | Cycle 1(1): loaded http://localhost:51242/tests/dromaeo/cssquery-ext.html (next: http://localhost:51242/tests/dromaeo/cssquery-jquery.html) 16:42:01 INFO - PID 3570 | RSS: Main: 174342144 16:42:01 INFO - PID 3570 | 16:45:02 INFO - PID 3570 | Cycle 1(1): loaded http://localhost:51242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51242/tests/dromaeo/cssquery-mootools.html) 16:45:03 INFO - PID 3570 | RSS: Main: 176300032 16:45:03 INFO - PID 3570 | 16:48:05 INFO - PID 3570 | Cycle 1(1): loaded http://localhost:51242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51242/tests/dromaeo/cssquery-prototype.html) 16:48:06 INFO - PID 3570 | RSS: Main: 173117440 16:48:06 INFO - PID 3570 | 16:51:09 INFO - PID 3570 | Cycle 1(1): loaded http://localhost:51242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51242/tests/dromaeo/cssquery-yui.html) 16:51:10 INFO - PID 3570 | RSS: Main: 177836032 16:51:10 INFO - PID 3570 | 16:54:12 INFO - PID 3570 | Cycle 1(1): loaded http://localhost:51242/tests/dromaeo/cssquery-yui.html (next: http://localhost:51242/tests/dromaeo/cssquery-dojo.html) 16:54:12 INFO - PID 3570 | RSS: Main: 178135040 16:54:12 INFO - PID 3570 | 16:54:12 INFO - PID 3570 | __start_tp_report 16:54:12 INFO - PID 3570 | _x_x_mozilla_page_load 16:54:12 INFO - PID 3570 | _x_x_mozilla_page_load_details 16:54:12 INFO - PID 3570 | |i|pagename|runs| 16:54:12 INFO - PID 3570 | |0;dojo.html;3079.7844150909445;3113.0505195915266;3125.7499400048;3140.0422871024334;3156.210947263184;6011.248593925759;6135.631862088278;6148.538859585527;6165.784197553086;6171.321154672988;5927.2590926134235;6020.247469066367;6040.184575082362;6067.605605635631;6074.058520929252;5574.944250557483;5586.720663966806;5607.102863541834;5618.634788738719;5624.493795558399;5605.2713147291;5615.4665306796;5635.436456354373;5640.041192997201;5648.209250704905;5589.7205139743055;5618.129189975561;5618.747156377953;5625.634333768312;5646.943530564703;5636.915446268309;5662.669272720907;5683.318001839806;5683.630564013326;5729.312482502085;5548.44515548446;5559.249501317311;5564.1931919871395;5581.804636837732;5621.747021384048;5810;5892.322382925976;5917.437843404869;5939.524838012949;5968.46283834457;6456.741730330781;6502.317256688027;6517.641529715911;6537.771178008747;6626.503012274099;6626.56927299724;6657.3342665733035;6703.262641109474;6735.696893639749;6749.257581666013;5989.520838332966;6076.635401875901;6096.481799047131;6121.387861213888;6202.131701561785;6831.4534837212905;6837.897431538531;6838;6838.589684618939;6840.794776156723;6430.903536446957;6484.513661475408;6506.837329066811;6527.804165875032;6532.41208291256;6539.640319782363;6548.738050477928;6557.737690492327;6625.204975402982;6642.833929151713;6375;6479.44924681398;6521.315261897527;6619.073329733841;6619.97390404488;6555.770548030796;6610.603363798187;6648.202215734143;6668.633225172662;6672.165979252593;5262.342207224097;5272.103742363807;5279.9208011879855;5343.171808369709;5359.410464848864;4540.931886021713;4573.108243892476;4619.330197121399;4628.421447319085;4640.443146822403;5602.943970560243;5636.210930469738;5650.717464126859;5651.180578816049;5689.743961521742;6503.382178693017;6642.43539299165;6644.966775166094;6661.500387470959;6665;5664.5751568631695;5685.204071430003;5687.914681279784;5688.232088667977;5709.029464990961;5712.11462223356;5819.563532735062;5834.562407819346;5835.912461313083;5838.7080645967535;6359.872802543923;6507.7071531781185;6516.511261655395;6529;6530.738770449224;6586.012098185119;6587.143671322889;6673.532852700265;6679.432248258952;6688.531802773759;6402;6406.359364063556;6487.9026814598765;6496.512761542997;6498.317676644074;6357.777477788255;6428.710708018057;6453.806385808433;6457.3865482779065;6514.674266286572;6605.702743376656;6611.801645950629;6612.768553100618;6620.768273110418;6622.602643841385;6310.432061114522;6385;6410.423061924263;6411;6412.294647588855;6320.5891617044745;6339.651319177305;6404.551681382445;6413.967930160506;6430.099786030053;6225.066240064027;6254.21822272216;6313.179286692701;6321.241451025722;6339.017452294902;6341.207349081365;6384.680765961776;6407.551471396958;6410.679466026773;6412.102305677302;6117.694115294128;6169.96915015422;6199.225096862892;6202.968985155046;6208.348123446973 16:54:12 INFO - PID 3570 | |1;ext.html;806.4032615864259;884.7655371326599;907.904670009649;1134.7617000429905;1152.6023521884947;30973.38053238937;31836.522452163237;42235.99999999996;43278.35082473757;43346;42549.36175957363;42962.99999999992;43031.35452968208;43044.78477607608;43054;7572.053493313336;7625.618719064052;7790.065192176947;7791.026121734783;7823.960880195591;7636.732714354971;7702.8844567331535;7717.382609391235;7743.760998240283;7748.341390981774;7716.691332346728;7781.610919454033;7781.961090194541;7783.221677832228;7784.026996625422;7726.884096738553;7767.766966990971;7771.339436147934;7773.494722843054;7777.300042996158;7596.9240307596765;7598.708219602626;7633.9618301908695;7638.732644357421;7645.235476452366;39096.21807563833;39177.80411097926;39479.802600987096;39535;39660.20679586421;3549.5563054618174;3584.0860580551794;3585.748997570171;3588.1926566522584;3589.210373717804;31690.524642130385;31819.204519886956;31901.52147717786;31906;31990;4661.7669116544375;4890.021995600894;4901.730404827733;4943.703377797307;4990.82532111378;191364;191577;192718;192739;192859;50439.747801261496;50564;50867.491325087016;51075.74462127666;51160.74419627878;52969.470305296454;53474.46525534773;53560.73219633955;53569.46430535723;53601.463985360424;13538.526151584647;16465;16761.078140702135;16803.243854026598;16839.494815155565;11219.102471802236;11227.214095013434;11236.66290011301;13615.455381784815;13967.930160349133;14870.330835112445;14977.326020328894;14986;15010.699786004438;15175.620609484851;5840.737166827503;5841.883162336814;5965.224520812353;5970.820875373745;6004.819855404345;1030.8299130643509;1031.2265800649513;1031.4481752262654;1049.7638031442864;1049.7952899184586;23632;23878.761212388003;23934.64098038531;24127.276181714577;24192.758072419052;1136.846525719034;1145.410113791398;1149.1324050341939;1150.108665784024;1160.5879912630894;12651.557195498297;12652.304123273309;12808.871911280954;12817.295048772221;12895.484180632857;225004;225721;226358;226652;227065;55632.443675562725;55741.72129139328;55840.441595584336;55934.72032639811;55983;1458.183417286323;1517.597836573309;1521.68805394896;1522.1856306875768;1540.5686407805833;23455.76544234604;23626.409339766644;23664.408389790395;23669.881650591637;23880.88059559691;38539.42190867083;38551.22897541977;38616.80691596524;38711.6128838708;38824.223515530095;184854;185024;185068;185219;185278;35289.64710352966;35467.645323546436;35473.82263088668;37491.2501749958;37521.06197344979;45990.540094598626;47820.760896195294;48441.75779121082;48917.755411222715;49162.75418622884;34699.47950780791;34963;35053.29893402169;35129.64870351366;35542.289154216254;33743.83128084344;34871.47692784661;34949.65050349566;35183.824080880455;35604.287914242086;36573.26853462969;37540.81229593835;37924.24151517009;37946.24107517889;38367.42448863213;33261.50107748337;33316.33367332591;33339.66660333366;33561.49657755087;33969.32061358808;25392.238232853044;25448.872755636105;25461.490770184864;25490.362740931625;25772.8711356442 16:54:12 INFO - PID 3570 | |2;jquery.html;573.277670135629;585.8388943040663;586.3286536915232;589.7051474262869;595.6396380189985;22358;22610.660840087392;23537.64693529596;23557.175498857545;23558.17546385877;26547.734522654766;26927.865360673168;27312.726872731266;27317.863410682967;27340.316492087706;6551.344865513458;6693.866122677544;6720.3951644352;6721.4286785623035;6721.529492935497;6068.514518838482;6497;6648.734050637969;6655.700493477805;6658.933410665904;6614.801555953329;6619;6621.93378066218;6624.403803657669;6642.800715978528;6590.044443555682;6592.077109204692;6612.86774264512;6624.768133115318;6636.070950066995;6176.629402235881;6177.969110154466;6178.876422471525;6182.752689892399;6193.752249909998;30647.54028689549;30807.845960770275;31459.52810707841;32241.83879080613;32340.353192936243;1955.687090065597;1993.8703984240979;2000.1699294792643;2001.7497812773404;2003.1887085730302;18263.63472730538;18855.34006309786;19186.808131918642;19226.423207303804;19391.80608193928;2283.6574513822893;2340.566995105911;2341.789238968484;2380.5595964746576;2389.462370966536;141665.29167354098;146332;147742;148477;148849;14511.419543218364;15048.849511504965;15177.544673659588;15531.767023494656;15808.683826323639;24067.51864962691;24286.14998475045;24311;24314.87842560776;24368.512629747307;10252.23108266883;10592.629257975934;10639.148868090535;10639.946800265949;10662.520186591622;8880.777980550409;9771.804563908681;9853.50732463374;9922.702318930444;10269.537870795832;8323.417360784686;9368.672096476726;9548.665796697082;9559.474228917337;9612.567434465465;2730.0924515391043;2791.1068458093214;2791.6091747155415;2800.705925877794;2822.8729707163225;952.3809523809441;963.9759006024765;970.3935040599625;975.6829030565095;992.4591097851688;20544.58910821775;20835.895820521102;21359.67960480594;24631;26139;1037.4968140451874;1040.2770074798093;1054.2040759226888;1061.5223149582719;1064.0689396777818;11538;11921.761564768656;11939.164258501994;11990.820137697941;12096.697582560506;494889;497432;502879;513177;513617;116004;118034;121950;123887;124681.37659311645;1253.0602048463652;1281.551456990046;1298.5130576033987;1298.5195477673321;1301.5704817410042;18717.59402087939;18870.24519019224;19177.904110479358;19244.807551924863;19808.40574782759;57851;58481;59183.704081479314;59203.40796591979;61277.38722612717;74486;74640;74960;75093.62453187918;75146.24853751392;12681.23912565249;13400.597982060553;14750.778738319148;14847.183404912834;15263.160526171167;24034.27897163088;24353.51292974166;24446.26661200167;24547.877260613583;24625.630615540424;17884.91057544704;18683.346082887034;19042.904785475985;19078.236870525023;19096.904515477334;17084.914575427043;18121.275148993893;18337.266509339475;18345;18991.525211869815;20074.698879516527;20200.797992019892;20479.07844147047;20771.169153233703;20928.267510637055;14672.413103475741;14718.77921831152;14729.116253024853;14796.926015370285;14835.629109272355;18935.71596426027;18983.24067037303;19097.14062867202;19221.711674325175;19357 16:54:12 INFO - PID 3570 | |3;mootools.html;1330.1885849631726;1366.897482688798;1373.9175649461033;1385.5912505810784;1400.5938277899397;13356.532521361754;14069.366878490468;14070.4371825127;14083.577492675211;14086.506972255973;13528.93235533821;14025.64935876601;14476.13143211408;14506.782398264035;14531.055481393701;2754.2701184186158;2800.187945495804;2800.4679110968955;2801;2802.7337402946687;2665.6134860445222;2676.157010541673;2706.512827691015;2707.9729202707917;2709.282040259333;2673.679158500973;2730.4539092181544;2784.554471284595;2786.205931309577;2788.2332358601343;2695.043259642828;2699.055330634272;2710.864456777173;2711.6745990481168;2711.8372897626205;2671.919842404731;2683.490136873988;2685.583734521133;2688.4891870544534;2688.973110268892;13510.93244533771;13765.587032389045;13767.586972390844;13802.723945521133;13827.170369777845;7598.392128629696;7912.050553933507;7917.485363451357;7920.366370690331;7923.168067352903;4984.551390374883;5205.063088644042;5284.682919024871;5355.7857685692525;5356.491133342326;1685.5701796042054;1712.725963845791;1727.06738361286;1753.833385828357;1759.0589034866414;22159.335219943427;22370.66444003341;22370.88814555933;22473.213437529772;22489.100435982706;6350.5554611177695;6543.345665433514;6570.77002304917;6722.899156512656;6762.053312536249;15559.45541906028;15635.452759153375;15660.608484787972;15661.765073523906;15661.843381566267;7508.774736757906;7656.693732250647;7658.693652253847;7684.231576842271;7691.730789422343;5843.824685259449;6115.755369785248;6227.657478838617;6261.029540421242;6410.326915673881;5071.112555302807;5143.897122057613;5269.1569348903995;5284.101702710548;5308.389535203501;1456.6722487440454;1498.820141583017;1503.7669161280019;1547.156799544251;1562.523430353753;908.6002159050129;919.296737995434;921.6774129054911;931.2317338196016;938.3666025432806;7554.848903021909;7573.545587264782;7590.316871481593;7662;7677.424193185533;713.62534669298;738.5642470942063;743.4015617428022;986.6546708651914;1020.3265844542715;5071.2139618359215;5099.18413053917;5316.043112239757;5357.919631205535;5484.7531861066345;17892.642147156985;18225;18933.715994260096;19019.1441385138;19049.238030478624;18486;18657.533561660795;18704.345347912757;18769.436916892515;18878.811211887707;9876.061774131445;10070.647527336509;10239.385636861813;10400.531976061247;10499.842502362471;8025.117237104031;8321.209485098905;8346.791330216793;8365.874511882448;8380.41337106421;16028.278727457526;16655.666886662442;16846.15769211559;17001.829981700026;17235.396761113803;16583.66832663316;16799.496015119566;17240.568985775455;17370.565735856704;17509.38717144945;10904.291221070605;11225.494852731483;11252.887471125185;11254.437278135896;11258.493367798632;12623.747525049632;12905.419256133684;13025.609231723063;13251.66870828237;13310.733785324433;9907.752306192402;10065.345752526355;10069.597216111273;10233.590656373959;10506.947465262625;10521.736956576147;10531.57873685044;10545.367277963347;10811.459427028774;10875.347479151276;9375.906240937777;9571.569279382584;9586.137247647745;9588.56851441673;9593.232541396532;7219.0976127984;7232.493725439169;7305.853882922418;7306.853862922818;7314.561126332437;7268;7335.889961650473;7442.702291908263;7498.775036748906;7639.541627502367 16:54:12 INFO - PID 3570 | |4;prototype.html;503.7583152414215;513.2250302043913;525.5926656840948;531.5800517591103;543.858596764841;6171.228596425447;6250.937490625098;6252.531060170489;6266;6329.873402531958;6068.787592434266;6204.224471941007;6214.782482613109;6220.253569571658;6226.906596401046;5133.974330128344;5158.587313014949;5160.6903585784785;5183.17069268917;5196.168613021917;4830.9275360869615;4949.257611358289;5101.7194054327;5109.386873575176;5154.252633368159;4899.191633380488;4938.678985865925;4943.678660887049;4946.554810067093;4951.975240123795;4943.134951383494;4944.381952255968;4962.900741985176;4974.129527332702;4978.103941290567;4453.621442177387;4461.709988850715;4465.59809617136;4469.82120715171;4472.351509031205;5859.648421094706;6056.69716514177;6065.818025459199;6067.848303792396;6092.0453580013655;5625.46558076982;5697.487226149625;5707.800226992077;5737.9713101434645;5800.941990580082;6529.233659428596;6539.378759017885;6570.671466426659;6576.86846263072;6588.703508342136;4868.245421959602;4880.097182021302;4895.804167833282;4921.917178220786;4930.654854160211;6593.911774699669;6690.297518760557;6762.087118239024;6815.488838337095;6913.170419549636;4953.479884612136;4957.702537847668;4978.660029786067;4991.3511243538105;5023.49765023502;5056.974715126401;5066.898662026739;5076.213186956028;5096.286519887219;5106.974465127725;5706;5717;5724.112762521816;5730.997075511768;5759.1937128802;6598.901016484757;6617.636030018299;6630.86738265232;6639.203295604559;6717.832054198684;1750.7986581543285;1768.2131451504215;1771.4419957713278;1773.5743421578986;1774.5918438759159;1144.3820337018083;1198.7722332756748;1203.2479700187382;1224.9571265005861;1234.2039384597065;5759.164921086419;5810.94189058104;5811.302643682785;5822.272215973004;5829.5044921181325;6456.257530383972;6634.502412319095;6635.170603674541;6681.231658359351;6691.431228345547;5713.428657134254;5745.052066409079;5793.565482588822;5797.159411885253;5827.038538641162;3556.772913344892;3594;3605.4591811228524;3610.891673249807;3628.38317486028;6584.473242140617;6622.602643841385;6640.435562977104;6641;6648.069270302161;6937.9653101735175;6939.20199177091;6970.268121847234;6989.685464154024;7040.683169257396;6545.70544325516;6559.8032059038305;6568.80293591193;6615.801525954229;6627.436667883284;6689.76585819494;6725.529212955046;6739.292374300628;6752.223494298219;6756.358145976124;6534.83662908412;6665;6675.532712710064;6731.225909020525;6737.831554210988;6521.8695626088165;6548.476121910331;6565.671716414256;6570.8357291066195;6602.7689030883685;4915.975420122876;5007.599392048554;5012.3233363495465;5025.0954828129825;5054.82308119214;6523.347665233438;6527.51043671736;6735.73057077731;6843.931560684329;6845.589264644137;6455.837949169195;6487.956293767448;6500.219973603198;6501.187351581052;6515.6742162892615;6473.676316184266;6475.870482590228;6503.674816259263;6543.378379053982;6554.492466732773;6124.755009799558;6396.200474940632;6425.518086143651;6456.451201647913;6481.578697384655;6493.155889734304;6508.967455162694;6567.179102612174;6569.540132190701;6640.402363787282;6355.173827402408;6397.840053998687;6435.871282574416;6441.452476539546;6442.130312407765 16:54:12 INFO - PID 3570 | |5;yui.html;890.4434728294816;901.7384958362074;914.7575892388522;920.4431319051973;923.8937522184947;18200.180991855366;18801;18817.62364752706;18884.905575472105;20714.27500037499;16657.666846663058;16705.83294167061;18489.7226541602;21467.785322146774;21568.460788480297;1028.9176865850732;1049.9527521261523;1051.458498873081;1051.831706926892;1051.8895515970828;1058.8041212375736;1062.537796058713;1062.904338609525;1063.0326402973296;1064.0636240108693;1056.6407421476697;1093.3276035238312;1104.4753741972538;1109.5949978257918;1117.8938000889902;1080.778440419712;1081.7241603391124;1087.9510422031028;1092.20814909191;1092.781443711257;799.3485309472819;800.2677550041705;800.6037011679256;803.0203152154362;803.8271771569141;19385.709214361796;19445.708314375297;19731.90134049335;19786.60426791456;20771.37685869411;20567;20858.269960551308;21170.788292117035;21185.258515951868;21326.146954121818;15812.209389530593;15872.52382428529;15908.045517268885;15950.521484355371;16116.83883161165;3460.688538031564;3501.91245218869;3502.8949131526097;3509.0525558099425;3512.5609298837644;129726;130620;131652;134948.32525837407;136353.3182334092;15501;15690.529284121494;15863.286152122952;15989.200539972955;16005.359785608445;30315.545266820573;30458.54312185319;30476.390472190433;30494.84752576223;31520.211994699857;8542.530160841214;8601.182887625666;8647.697330593524;8680.305575554066;8697.652093916173;4282.079352939133;4288.699791014599;4332.631726303236;4368.191884501314;4396.692231543761;5285.9735701322015;5340.412554618989;5382.4886635769535;5390.245365648812;5419.078756611386;955.7085089047769;981.097390400833;995.7311525887969;998.2612866478898;998.5805961496189;639.2488825629866;656.3043174235326;658.9637569933603;665.3280187011056;677.5731289287717;11275.436228188557;11573.42132893352;11698.590549330733;12668.493260269486;13707.314634268247;678.1997243253008;686.6189264957897;689.931006899306;693.6011793218919;698.6192525073745;5362.276092727432;5494.945050549523;5514.924589704969;5560.138178582326;5602.159676048544;101968.49015754873;103137.48431257796;104948.47525762323;105426;109764.4511777436;91776;91995;92487;92793;93251.5337423322;83962.5801870999;84311.15688843156;84448.57775711082;85174.57412712897;85833;11717.355545445082;11846.348450835287;11970.76058478843;12000.699982500508;12026.338551379758;35547.28905421929;37330.62669373272;37833.62166378301;38366.616333837424;38798;65070;66225;67261.32738672747;67645;70264.29735702577;18614.627707445503;19023.714644280622;19075.713864292324;19241.5189620255;19384.612307753483;18891.716624250374;18937.147828347483;19133;19472.805271947098;20221.191152353742;18128.184231709867;18147.73826259501;18156;18250;18456.538586534905;17616.383426580007;17626;17874.374396896048;18619.16213770411;18723.97018164014;17881.73177402366;18481.53796155107;19071.90464047671;19135;19148.234070637576;12814.935925320313;13685.794713079113;13940.512082077485;14021.789673155115;14394.784078239045;13456.932715336361;13617.182969021891;13878.306084695523;13938.372773225432;14000 16:54:12 INFO - PID 3570 | __end_tp_report 16:54:12 INFO - PID 3570 | __start_cc_report 16:54:12 INFO - PID 3570 | _x_x_mozilla_cycle_collect,376 16:54:12 INFO - PID 3570 | __end_cc_report 16:54:12 INFO - PID 3570 | __startTimestamp1501718052582__endTimestamp 16:54:12 INFO - PID 3570 | 16:54:12 INFO - PID 3570 | ------- Summary: start ------- 16:54:12 INFO - PID 3570 | Number of tests: 6 16:54:12 INFO - PID 3570 | 16:54:12 INFO - PID 3570 | [#0] dojo.html Cycles:165 Average:6049.14 Median:6310.43 stddev:716.40 (11.4%) stddev-sans-first:679.67 16:54:12 INFO - PID 3570 | Values: 3079.8 3113.1 3125.7 3140.0 3156.2 6011.2 6135.6 6148.5 6165.8 6171.3 5927.3 6020.2 6040.2 6067.6 6074.1 5574.9 5586.7 5607.1 5618.6 5624.5 5605.3 5615.5 5635.4 5640.0 5648.2 5589.7 5618.1 5618.7 5625.6 5646.9 5636.9 5662.7 5683.3 5683.6 5729.3 5548.4 5559.2 5564.2 5581.8 5621.7 5810.0 5892.3 5917.4 5939.5 5968.5 6456.7 6502.3 6517.6 6537.8 6626.5 6626.6 6657.3 6703.3 6735.7 6749.3 5989.5 6076.6 6096.5 6121.4 6202.1 6831.5 6837.9 6838.0 6838.6 6840.8 6430.9 6484.5 6506.8 6527.8 6532.4 6539.6 6548.7 6557.7 6625.2 6642.8 6375.0 6479.4 6521.3 6619.1 6620.0 6555.8 6610.6 6648.2 6668.6 6672.2 5262.3 5272.1 5279.9 5343.2 5359.4 4540.9 4573.1 4619.3 4628.4 4640.4 5602.9 5636.2 5650.7 5651.2 5689.7 6503.4 6642.4 6645.0 6661.5 6665.0 5664.6 5685.2 5687.9 5688.2 5709.0 5712.1 5819.6 5834.6 5835.9 5838.7 6359.9 6507.7 6516.5 6529.0 6530.7 6586.0 6587.1 6673.5 6679.4 6688.5 6402.0 6406.4 6487.9 6496.5 6498.3 6357.8 6428.7 6453.8 6457.4 6514.7 6605.7 6611.8 6612.8 6620.8 6622.6 6310.4 6385.0 6410.4 6411.0 6412.3 6320.6 6339.7 6404.6 6414.0 6430.1 6225.1 6254.2 6313.2 6321.2 6339.0 6341.2 6384.7 6407.6 6410.7 6412.1 6117.7 6170.0 6199.2 6203.0 6208.3 16:54:12 INFO - PID 3570 | 16:54:12 INFO - PID 3570 | [#1] ext.html Cycles:180 Average:37973.41 Median:19545.92 stddev:52318.01 (267.7%) stddev-sans-first:52390.34 16:54:12 INFO - PID 3570 | Values: 806.4 884.8 907.9 1134.8 1152.6 30973.4 31836.5 42236.0 43278.4 43346.0 42549.4 42963.0 43031.4 43044.8 43054.0 7572.1 7625.6 7790.1 7791.0 7824.0 7636.7 7702.9 7717.4 7743.8 7748.3 7716.7 7781.6 7782.0 7783.2 7784.0 7726.9 7767.8 7771.3 7773.5 7777.3 7596.9 7598.7 7634.0 7638.7 7645.2 39096.2 39177.8 39479.8 39535.0 39660.2 3549.6 3584.1 3585.7 3588.2 3589.2 31690.5 31819.2 31901.5 31906.0 31990.0 4661.8 4890.0 4901.7 4943.7 4990.8 191364.0 191577.0 192718.0 192739.0 192859.0 50439.7 50564.0 50867.5 51075.7 51160.7 52969.5 53474.5 53560.7 53569.5 53601.5 13538.5 16465.0 16761.1 16803.2 16839.5 11219.1 11227.2 11236.7 13615.5 13967.9 14870.3 14977.3 14986.0 15010.7 15175.6 5840.7 5841.9 5965.2 5970.8 6004.8 1030.8 1031.2 1031.4 1049.8 1049.8 23632.0 23878.8 23934.6 24127.3 24192.8 1136.8 1145.4 1149.1 1150.1 1160.6 12651.6 12652.3 12808.9 12817.3 12895.5 225004.0 225721.0 226358.0 226652.0 227065.0 55632.4 55741.7 55840.4 55934.7 55983.0 1458.2 1517.6 1521.7 1522.2 1540.6 23455.8 23626.4 23664.4 23669.9 23880.9 38539.4 38551.2 38616.8 38711.6 38824.2 184854.0 185024.0 185068.0 185219.0 185278.0 35289.6 35467.6 35473.8 37491.3 37521.1 45990.5 47820.8 48441.8 48917.8 49162.8 34699.5 34963.0 35053.3 35129.6 35542.3 33743.8 34871.5 34949.7 35183.8 35604.3 36573.3 37540.8 37924.2 37946.2 38367.4 33261.5 33316.3 33339.7 33561.5 33969.3 25392.2 25448.9 25461.5 25490.4 25772.9 16:54:12 INFO - PID 3570 | 16:54:12 INFO - PID 3570 | [#2] jquery.html Cycles:180 Average:36195.08 Median:23557.68 stddev:85227.73 (361.8%) stddev-sans-first:85424.85 16:54:12 INFO - PID 3570 | Values: 573.3 585.8 586.3 589.7 595.6 22358.0 22610.7 23537.6 23557.2 23558.2 26547.7 26927.9 27312.7 27317.9 27340.3 6551.3 6693.9 6720.4 6721.4 6721.5 6068.5 6497.0 6648.7 6655.7 6658.9 6614.8 6619.0 6621.9 6624.4 6642.8 6590.0 6592.1 6612.9 6624.8 6636.1 6176.6 6178.0 6178.9 6182.8 6193.8 30647.5 30807.8 31459.5 32241.8 32340.4 1955.7 1993.9 2000.2 2001.7 2003.2 18263.6 18855.3 19186.8 19226.4 19391.8 2283.7 2340.6 2341.8 2380.6 2389.5 141665.3 146332.0 147742.0 148477.0 148849.0 14511.4 15048.8 15177.5 15531.8 15808.7 24067.5 24286.1 24311.0 24314.9 24368.5 10252.2 10592.6 10639.1 10639.9 10662.5 8880.8 9771.8 9853.5 9922.7 10269.5 8323.4 9368.7 9548.7 9559.5 9612.6 2730.1 2791.1 2791.6 2800.7 2822.9 952.4 964.0 970.4 975.7 992.5 20544.6 20835.9 21359.7 24631.0 26139.0 1037.5 1040.3 1054.2 1061.5 1064.1 11538.0 11921.8 11939.2 11990.8 12096.7 494889.0 497432.0 502879.0 513177.0 513617.0 116004.0 118034.0 121950.0 123887.0 124681.4 1253.1 1281.6 1298.5 1298.5 1301.6 18717.6 18870.2 19177.9 19244.8 19808.4 57851.0 58481.0 59183.7 59203.4 61277.4 74486.0 74640.0 74960.0 75093.6 75146.2 12681.2 13400.6 14750.8 14847.2 15263.2 24034.3 24353.5 24446.3 24547.9 24625.6 17884.9 18683.3 19042.9 19078.2 19096.9 17084.9 18121.3 18337.3 18345.0 18991.5 20074.7 20200.8 20479.1 20771.2 20928.3 14672.4 14718.8 14729.1 14796.9 14835.6 18935.7 18983.2 19097.1 19221.7 19357.0 16:54:12 INFO - PID 3570 | 16:54:12 INFO - PID 3570 | [#3] mootools.html Cycles:180 Average:8623.20 Median:2688.73 stddev:5777.89 (214.9%) stddev-sans-first:5768.11 16:54:12 INFO - PID 3570 | Values: 1330.2 1366.9 1373.9 1385.6 1400.6 13356.5 14069.4 14070.4 14083.6 14086.5 13528.9 14025.6 14476.1 14506.8 14531.1 2754.3 2800.2 2800.5 2801.0 2802.7 2665.6 2676.2 2706.5 2708.0 2709.3 2673.7 2730.5 2784.6 2786.2 2788.2 2695.0 2699.1 2710.9 2711.7 2711.8 2671.9 2683.5 2685.6 2688.5 2689.0 13510.9 13765.6 13767.6 13802.7 13827.2 7598.4 7912.1 7917.5 7920.4 7923.2 4984.6 5205.1 5284.7 5355.8 5356.5 1685.6 1712.7 1727.1 1753.8 1759.1 22159.3 22370.7 22370.9 22473.2 22489.1 6350.6 6543.3 6570.8 6722.9 6762.1 15559.5 15635.5 15660.6 15661.8 15661.8 7508.8 7656.7 7658.7 7684.2 7691.7 5843.8 6115.8 6227.7 6261.0 6410.3 5071.1 5143.9 5269.2 5284.1 5308.4 1456.7 1498.8 1503.8 1547.2 1562.5 908.6 919.3 921.7 931.2 938.4 7554.8 7573.5 7590.3 7662.0 7677.4 713.6 738.6 743.4 986.7 1020.3 5071.2 5099.2 5316.0 5357.9 5484.8 17892.6 18225.0 18933.7 19019.1 19049.2 18486.0 18657.5 18704.3 18769.4 18878.8 9876.1 10070.6 10239.4 10400.5 10499.8 8025.1 8321.2 8346.8 8365.9 8380.4 16028.3 16655.7 16846.2 17001.8 17235.4 16583.7 16799.5 17240.6 17370.6 17509.4 10904.3 11225.5 11252.9 11254.4 11258.5 12623.7 12905.4 13025.6 13251.7 13310.7 9907.8 10065.3 10069.6 10233.6 10506.9 10521.7 10531.6 10545.4 10811.5 10875.3 9375.9 9571.6 9586.1 9588.6 9593.2 7219.1 7232.5 7305.9 7306.9 7314.6 7268.0 7335.9 7442.7 7498.8 7639.5 16:54:12 INFO - PID 3570 | 16:54:12 INFO - PID 3570 | [#4] prototype.html Cycles:180 Average:5524.35 Median:6108.40 stddev:1546.13 (25.3%) stddev-sans-first:1503.84 16:54:12 INFO - PID 3570 | Values: 503.8 513.2 525.6 531.6 543.9 6171.2 6250.9 6252.5 6266.0 6329.9 6068.8 6204.2 6214.8 6220.3 6226.9 5134.0 5158.6 5160.7 5183.2 5196.2 4830.9 4949.3 5101.7 5109.4 5154.3 4899.2 4938.7 4943.7 4946.6 4952.0 4943.1 4944.4 4962.9 4974.1 4978.1 4453.6 4461.7 4465.6 4469.8 4472.4 5859.6 6056.7 6065.8 6067.8 6092.0 5625.5 5697.5 5707.8 5738.0 5800.9 6529.2 6539.4 6570.7 6576.9 6588.7 4868.2 4880.1 4895.8 4921.9 4930.7 6593.9 6690.3 6762.1 6815.5 6913.2 4953.5 4957.7 4978.7 4991.4 5023.5 5057.0 5066.9 5076.2 5096.3 5107.0 5706.0 5717.0 5724.1 5731.0 5759.2 6598.9 6617.6 6630.9 6639.2 6717.8 1750.8 1768.2 1771.4 1773.6 1774.6 1144.4 1198.8 1203.2 1225.0 1234.2 5759.2 5810.9 5811.3 5822.3 5829.5 6456.3 6634.5 6635.2 6681.2 6691.4 5713.4 5745.1 5793.6 5797.2 5827.0 3556.8 3594.0 3605.5 3610.9 3628.4 6584.5 6622.6 6640.4 6641.0 6648.1 6938.0 6939.2 6970.3 6989.7 7040.7 6545.7 6559.8 6568.8 6615.8 6627.4 6689.8 6725.5 6739.3 6752.2 6756.4 6534.8 6665.0 6675.5 6731.2 6737.8 6521.9 6548.5 6565.7 6570.8 6602.8 4916.0 5007.6 5012.3 5025.1 5054.8 6523.3 6527.5 6735.7 6843.9 6845.6 6455.8 6488.0 6500.2 6501.2 6515.7 6473.7 6475.9 6503.7 6543.4 6554.5 6124.8 6396.2 6425.5 6456.5 6481.6 6493.2 6509.0 6567.2 6569.5 6640.4 6355.2 6397.8 6435.9 6441.5 6442.1 16:54:12 INFO - PID 3570 | 16:54:12 INFO - PID 3570 | [#5] yui.html Cycles:180 Average:23385.27 Median:19415.71 stddev:31815.47 (163.9%) stddev-sans-first:31859.88 16:54:12 INFO - PID 3570 | Values: 890.4 901.7 914.8 920.4 923.9 18200.2 18801.0 18817.6 18884.9 20714.3 16657.7 16705.8 18489.7 21467.8 21568.5 1028.9 1050.0 1051.5 1051.8 1051.9 1058.8 1062.5 1062.9 1063.0 1064.1 1056.6 1093.3 1104.5 1109.6 1117.9 1080.8 1081.7 1088.0 1092.2 1092.8 799.3 800.3 800.6 803.0 803.8 19385.7 19445.7 19731.9 19786.6 20771.4 20567.0 20858.3 21170.8 21185.3 21326.1 15812.2 15872.5 15908.0 15950.5 16116.8 3460.7 3501.9 3502.9 3509.1 3512.6 129726.0 130620.0 131652.0 134948.3 136353.3 15501.0 15690.5 15863.3 15989.2 16005.4 30315.5 30458.5 30476.4 30494.8 31520.2 8542.5 8601.2 8647.7 8680.3 8697.7 4282.1 4288.7 4332.6 4368.2 4396.7 5286.0 5340.4 5382.5 5390.2 5419.1 955.7 981.1 995.7 998.3 998.6 639.2 656.3 659.0 665.3 677.6 11275.4 11573.4 11698.6 12668.5 13707.3 678.2 686.6 689.9 693.6 698.6 5362.3 5494.9 5514.9 5560.1 5602.2 101968.5 103137.5 104948.5 105426.0 109764.5 91776.0 91995.0 92487.0 92793.0 93251.5 83962.6 84311.2 84448.6 85174.6 85833.0 11717.4 11846.3 11970.8 12000.7 12026.3 35547.3 37330.6 37833.6 38366.6 38798.0 65070.0 66225.0 67261.3 67645.0 70264.3 18614.6 19023.7 19075.7 19241.5 19384.6 18891.7 18937.1 19133.0 19472.8 20221.2 18128.2 18147.7 18156.0 18250.0 18456.5 17616.4 17626.0 17874.4 18619.2 18724.0 17881.7 18481.5 19071.9 19135.0 19148.2 12814.9 13685.8 13940.5 14021.8 14394.8 13456.9 13617.2 13878.3 13938.4 14000.0 16:54:12 INFO - PID 3570 | -------- Summary: end -------- 16:54:12 INFO - PID 3570 | 16:54:13 INFO - TEST-INFO | 3570: exit 0 16:54:13 INFO - TEST-OK | dromaeo_css | took 1115255ms 16:54:13 INFO - TEST-START | kraken 16:54:13 INFO - Initialising browser for kraken test... 16:54:13 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51242/getInfo.html -profile /tmp/tmpEQxdcX/profile 16:54:13 INFO - TEST-INFO | started process 3939 (/builds/slave/test/build/application/firefox/firefox http://localhost:51242/getInfo.html) 16:54:19 INFO - TEST-INFO | 3939: exit 0 16:54:19 INFO - Browser initialized. 16:54:19 INFO - Running cycle 1/1 for kraken test... 16:54:19 INFO - TEST-INFO | started process 4165 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEQxdcX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:54:20 INFO - PID 4165 | 16:54:20 INFO - PID 4165 | (/builds/slave/test/build/application/firefox/firefox:4219): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:54:20 INFO - PID 4165 | 16:54:30 INFO - PID 4165 | RSS: Main: 174260224 16:54:30 INFO - PID 4165 | 16:54:33 INFO - PID 4165 | Cycle 1(1): loaded http://localhost:51242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51242/tests/kraken/driver.html?testName=audio-beat-detection) 16:54:34 INFO - PID 4165 | RSS: Main: 179195904 16:54:34 INFO - PID 4165 | 16:54:37 INFO - PID 4165 | Cycle 1(1): loaded http://localhost:51242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51242/tests/kraken/driver.html?testName=audio-dft) 16:54:37 INFO - PID 4165 | RSS: Main: 179519488 16:54:37 INFO - PID 4165 | 16:54:41 INFO - PID 4165 | Cycle 1(1): loaded http://localhost:51242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51242/tests/kraken/driver.html?testName=audio-fft) 16:54:41 INFO - PID 4165 | RSS: Main: 174604288 16:54:41 INFO - PID 4165 | 16:54:43 INFO - PID 4165 | Cycle 1(1): loaded http://localhost:51242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51242/tests/kraken/driver.html?testName=audio-oscillator) 16:54:44 INFO - PID 4165 | RSS: Main: 175157248 16:54:44 INFO - PID 4165 | 16:54:46 INFO - PID 4165 | Cycle 1(1): loaded http://localhost:51242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:54:47 INFO - PID 4165 | RSS: Main: 175202304 16:54:47 INFO - PID 4165 | 16:54:50 INFO - PID 4165 | Cycle 1(1): loaded http://localhost:51242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51242/tests/kraken/driver.html?testName=imaging-darkroom) 16:54:51 INFO - PID 4165 | RSS: Main: 177233920 16:54:51 INFO - PID 4165 | 16:54:54 INFO - PID 4165 | Cycle 1(1): loaded http://localhost:51242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51242/tests/kraken/driver.html?testName=imaging-desaturate) 16:54:55 INFO - PID 4165 | RSS: Main: 177528832 16:54:55 INFO - PID 4165 | 16:54:58 INFO - PID 4165 | Cycle 1(1): loaded http://localhost:51242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51242/tests/kraken/driver.html?testName=json-parse-financial) 16:54:58 INFO - PID 4165 | RSS: Main: 178286592 16:54:58 INFO - PID 4165 | 16:55:00 INFO - PID 4165 | Cycle 1(1): loaded http://localhost:51242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:55:01 INFO - PID 4165 | RSS: Main: 178016256 16:55:01 INFO - PID 4165 | 16:55:03 INFO - PID 4165 | Cycle 1(1): loaded http://localhost:51242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51242/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:55:03 INFO - PID 4165 | RSS: Main: 174833664 16:55:03 INFO - PID 4165 | 16:55:05 INFO - PID 4165 | Cycle 1(1): loaded http://localhost:51242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:55:06 INFO - PID 4165 | RSS: Main: 175083520 16:55:06 INFO - PID 4165 | 16:55:09 INFO - PID 4165 | Cycle 1(1): loaded http://localhost:51242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:55:10 INFO - PID 4165 | RSS: Main: 175644672 16:55:10 INFO - PID 4165 | 16:55:13 INFO - PID 4165 | Cycle 1(1): loaded http://localhost:51242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:55:13 INFO - PID 4165 | RSS: Main: 175292416 16:55:13 INFO - PID 4165 | 16:55:15 INFO - PID 4165 | Cycle 1(1): loaded http://localhost:51242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51242/tests/kraken/driver.html?testName=ai-astar) 16:55:15 INFO - PID 4165 | RSS: Main: 177246208 16:55:15 INFO - PID 4165 | 16:55:15 INFO - PID 4165 | __start_tp_report 16:55:15 INFO - PID 4165 | _x_x_mozilla_page_load 16:55:15 INFO - PID 4165 | _x_x_mozilla_page_load_details 16:55:15 INFO - PID 4165 | |i|pagename|runs| 16:55:15 INFO - PID 4165 | |0;ai-astar;97;107;103;109;111;111;109;112;112;111 16:55:15 INFO - PID 4165 | |1;audio-beat-detection;147;117;141;165;117;141;154;119;182;119 16:55:15 INFO - PID 4165 | |2;audio-dft;163;163;171;162;163;163;171;167;163;169 16:55:15 INFO - PID 4165 | |3;audio-fft;76;74;74;161;80;76;78;89;100;74 16:55:15 INFO - PID 4165 | |4;audio-oscillator;78;77;138;79;79;78;154;79;77;126 16:55:15 INFO - PID 4165 | |5;imaging-gaussian-blur;114;113;113;113;117;117;114;114;114;117 16:55:15 INFO - PID 4165 | |6;imaging-darkroom;151;153;151;150;155;154;151;151;152;153 16:55:15 INFO - PID 4165 | |7;imaging-desaturate;94;95;95;101;98;94;93;99;94;97 16:55:15 INFO - PID 4165 | |8;json-parse-financial;57;57;57;56;56;61;56;57;55;57 16:55:15 INFO - PID 4165 | |9;json-stringify-tinderbox;56;54;54;54;61;54;53;54;60;55 16:55:15 INFO - PID 4165 | |10;stanford-crypto-aes;81;87;76;88;76;86;81;87;87;78 16:55:15 INFO - PID 4165 | |11;stanford-crypto-ccm;136;136;133;131;132;126;131;135;133;173 16:55:15 INFO - PID 4165 | |12;stanford-crypto-pbkdf2;179;181;181;195;174;175;173;179;174;176 16:55:15 INFO - PID 4165 | |13;stanford-crypto-sha256-iterative;67;66;67;66;68;71;67;69;66;71 16:55:15 INFO - PID 4165 | __end_tp_report 16:55:15 INFO - PID 4165 | __start_cc_report 16:55:15 INFO - PID 4165 | _x_x_mozilla_cycle_collect,957 16:55:15 INFO - PID 4165 | __end_cc_report 16:55:15 INFO - PID 4165 | __startTimestamp1501718115930__endTimestamp 16:55:15 INFO - PID 4165 | 16:55:15 INFO - PID 4165 | ------- Summary: start ------- 16:55:15 INFO - PID 4165 | Number of tests: 14 16:55:15 INFO - PID 4165 | 16:55:15 INFO - PID 4165 | [#0] ai-astar Cycles:10 Average:108.20 Median:111.00 stddev:4.80 (4.3%) stddev-sans-first:2.92 16:55:15 INFO - PID 4165 | Values: 97.0 107.0 103.0 109.0 111.0 111.0 109.0 112.0 112.0 111.0 16:55:15 INFO - PID 4165 | 16:55:15 INFO - PID 4165 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:144.00 stddev:22.55 (15.7%) stddev-sans-first:23.78 16:55:15 INFO - PID 4165 | Values: 147.0 117.0 141.0 165.0 117.0 141.0 154.0 119.0 182.0 119.0 16:55:15 INFO - PID 4165 | 16:55:15 INFO - PID 4165 | [#2] audio-dft Cycles:10 Average:165.50 Median:165.00 stddev:3.63 (2.2%) stddev-sans-first:3.73 16:55:15 INFO - PID 4165 | Values: 163.0 163.0 171.0 162.0 163.0 163.0 171.0 167.0 163.0 169.0 16:55:15 INFO - PID 4165 | 16:55:15 INFO - PID 4165 | [#3] audio-fft Cycles:10 Average:88.20 Median:76.00 stddev:26.90 (35.4%) stddev-sans-first:28.17 16:55:15 INFO - PID 4165 | Values: 76.0 74.0 74.0 161.0 80.0 76.0 78.0 89.0 100.0 74.0 16:55:15 INFO - PID 4165 | 16:55:15 INFO - PID 4165 | [#4] audio-oscillator Cycles:10 Average:96.50 Median:78.00 stddev:30.30 (38.8%) stddev-sans-first:31.39 16:55:15 INFO - PID 4165 | Values: 78.0 77.0 138.0 79.0 79.0 78.0 154.0 79.0 77.0 126.0 16:55:15 INFO - PID 4165 | 16:55:15 INFO - PID 4165 | [#5] imaging-gaussian-blur Cycles:10 Average:114.60 Median:114.00 stddev:1.71 (1.5%) stddev-sans-first:1.80 16:55:15 INFO - PID 4165 | Values: 114.0 113.0 113.0 113.0 117.0 117.0 114.0 114.0 114.0 117.0 16:55:15 INFO - PID 4165 | 16:55:15 INFO - PID 4165 | [#6] imaging-darkroom Cycles:10 Average:152.10 Median:152.50 stddev:1.60 (1.0%) stddev-sans-first:1.64 16:55:15 INFO - PID 4165 | Values: 151.0 153.0 151.0 150.0 155.0 154.0 151.0 151.0 152.0 153.0 16:55:15 INFO - PID 4165 | 16:55:15 INFO - PID 4165 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:95.00 stddev:2.62 (2.8%) stddev-sans-first:2.68 16:55:15 INFO - PID 4165 | Values: 94.0 95.0 95.0 101.0 98.0 94.0 93.0 99.0 94.0 97.0 16:55:15 INFO - PID 4165 | 16:55:15 INFO - PID 4165 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.00 stddev:1.60 (2.8%) stddev-sans-first:1.69 16:55:15 INFO - PID 4165 | Values: 57.0 57.0 57.0 56.0 56.0 61.0 56.0 57.0 55.0 57.0 16:55:15 INFO - PID 4165 | 16:55:15 INFO - PID 4165 | [#9] json-stringify-tinderbox Cycles:10 Average:55.50 Median:54.50 stddev:2.76 (5.1%) stddev-sans-first:2.92 16:55:15 INFO - PID 4165 | Values: 56.0 54.0 54.0 54.0 61.0 54.0 53.0 54.0 60.0 55.0 16:55:15 INFO - PID 4165 | 16:55:15 INFO - PID 4165 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:86.50 stddev:4.85 (5.6%) stddev-sans-first:5.11 16:55:15 INFO - PID 4165 | Values: 81.0 87.0 76.0 88.0 76.0 86.0 81.0 87.0 87.0 78.0 16:55:15 INFO - PID 4165 | 16:55:15 INFO - PID 4165 | [#11] stanford-crypto-ccm Cycles:10 Average:136.60 Median:134.00 stddev:13.13 (9.8%) stddev-sans-first:13.92 16:55:15 INFO - PID 4165 | Values: 136.0 136.0 133.0 131.0 132.0 126.0 131.0 135.0 133.0 173.0 16:55:15 INFO - PID 4165 | 16:55:15 INFO - PID 4165 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.70 Median:179.00 stddev:6.45 (3.6%) stddev-sans-first:6.84 16:55:15 INFO - PID 4165 | Values: 179.0 181.0 181.0 195.0 174.0 175.0 173.0 179.0 174.0 176.0 16:55:15 INFO - PID 4165 | 16:55:15 INFO - PID 4165 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:67.50 stddev:1.93 (2.9%) stddev-sans-first:2.03 16:55:15 INFO - PID 4165 | Values: 67.0 66.0 67.0 66.0 68.0 71.0 67.0 69.0 66.0 71.0 16:55:15 INFO - PID 4165 | -------- Summary: end -------- 16:55:15 INFO - PID 4165 | 16:55:16 INFO - PID 4165 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:55:16 INFO - TEST-INFO | 4165: exit 0 16:55:16 INFO - TEST-OK | kraken | took 63359ms 16:55:16 INFO - SUITE-END | took 1178s 16:55:17 INFO - Completed test suite (00:19:39) 16:55:17 INFO - javascript benchmark 16:55:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8520.12155663573, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3079.7844150909445, 3113.0505195915266, 3125.7499400048, 3140.0422871024334, 3156.210947263184, 6011.248593925759, 6135.631862088278, 6148.538859585527, 6165.784197553086, 6171.321154672988, 5927.2590926134235, 6020.247469066367, 6040.184575082362, 6067.605605635631, 6074.058520929252, 5574.944250557483, 5586.720663966806, 5607.102863541834, 5618.634788738719, 5624.493795558399, 5605.2713147291, 5615.4665306796, 5635.436456354373, 5640.041192997201, 5648.209250704905, 5589.7205139743055, 5618.129189975561, 5618.747156377953, 5625.634333768312, 5646.943530564703, 5636.915446268309, 5662.669272720907, 5683.318001839806, 5683.630564013326, 5729.312482502085, 5548.44515548446, 5559.249501317311, 5564.1931919871395, 5581.804636837732, 5621.747021384048, 5810.0, 5892.322382925976, 5917.437843404869, 5939.524838012949, 5968.46283834457, 6456.741730330781, 6502.317256688027, 6517.641529715911, 6537.771178008747, 6626.503012274099, 6626.56927299724, 6657.3342665733035, 6703.262641109474, 6735.696893639749, 6749.257581666013, 5989.520838332966, 6076.635401875901, 6096.481799047131, 6121.387861213888, 6202.131701561785, 6831.4534837212905, 6837.897431538531, 6838.0, 6838.589684618939, 6840.794776156723, 6430.903536446957, 6484.513661475408, 6506.837329066811, 6527.804165875032, 6532.41208291256, 6539.640319782363, 6548.738050477928, 6557.737690492327, 6625.204975402982, 6642.833929151713, 6375.0, 6479.44924681398, 6521.315261897527, 6619.073329733841, 6619.97390404488, 6555.770548030796, 6610.603363798187, 6648.202215734143, 6668.633225172662, 6672.165979252593, 5262.342207224097, 5272.103742363807, 5279.9208011879855, 5343.171808369709, 5359.410464848864, 4540.931886021713, 4573.108243892476, 4619.330197121399, 4628.421447319085, 4640.443146822403, 5602.943970560243, 5636.210930469738, 5650.717464126859, 5651.180578816049, 5689.743961521742, 6503.382178693017, 6642.43539299165, 6644.966775166094, 6661.500387470959, 6665.0, 5664.5751568631695, 5685.204071430003, 5687.914681279784, 5688.232088667977, 5709.029464990961, 5712.11462223356, 5819.563532735062, 5834.562407819346, 5835.912461313083, 5838.7080645967535, 6359.872802543923, 6507.7071531781185, 6516.511261655395, 6529.0, 6530.738770449224, 6586.012098185119, 6587.143671322889, 6673.532852700265, 6679.432248258952, 6688.531802773759, 6402.0, 6406.359364063556, 6487.9026814598765, 6496.512761542997, 6498.317676644074, 6357.777477788255, 6428.710708018057, 6453.806385808433, 6457.3865482779065, 6514.674266286572, 6605.702743376656, 6611.801645950629, 6612.768553100618, 6620.768273110418, 6622.602643841385, 6310.432061114522, 6385.0, 6410.423061924263, 6411.0, 6412.294647588855, 6320.5891617044745, 6339.651319177305, 6404.551681382445, 6413.967930160506, 6430.099786030053, 6225.066240064027, 6254.21822272216, 6313.179286692701, 6321.241451025722, 6339.017452294902, 6341.207349081365, 6384.680765961776, 6407.551471396958, 6410.679466026773, 6412.102305677302, 6117.694115294128, 6169.96915015422, 6199.225096862892, 6202.968985155046, 6208.348123446973], "value": 5995.609050436088, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [806.4032615864259, 884.7655371326599, 907.904670009649, 1134.7617000429905, 1152.6023521884947, 30973.38053238937, 31836.522452163237, 42235.99999999996, 43278.35082473757, 43346.0, 42549.36175957363, 42962.99999999992, 43031.35452968208, 43044.78477607608, 43054.0, 7572.053493313336, 7625.618719064052, 7790.065192176947, 7791.026121734783, 7823.960880195591, 7636.732714354971, 7702.8844567331535, 7717.382609391235, 7743.760998240283, 7748.341390981774, 7716.691332346728, 7781.610919454033, 7781.961090194541, 7783.221677832228, 7784.026996625422, 7726.884096738553, 7767.766966990971, 7771.339436147934, 7773.494722843054, 7777.300042996158, 7596.9240307596765, 7598.708219602626, 7633.9618301908695, 7638.732644357421, 7645.235476452366, 39096.21807563833, 39177.80411097926, 39479.802600987096, 39535.0, 39660.20679586421, 3549.5563054618174, 3584.0860580551794, 3585.748997570171, 3588.1926566522584, 3589.210373717804, 31690.524642130385, 31819.204519886956, 31901.52147717786, 31906.0, 31990.0, 4661.7669116544375, 4890.021995600894, 4901.730404827733, 4943.703377797307, 4990.82532111378, 191364.0, 191577.0, 192718.0, 192739.0, 192859.0, 50439.747801261496, 50564.0, 50867.491325087016, 51075.74462127666, 51160.74419627878, 52969.470305296454, 53474.46525534773, 53560.73219633955, 53569.46430535723, 53601.463985360424, 13538.526151584647, 16465.0, 16761.078140702135, 16803.243854026598, 16839.494815155565, 11219.102471802236, 11227.214095013434, 11236.66290011301, 13615.455381784815, 13967.930160349133, 14870.330835112445, 14977.326020328894, 14986.0, 15010.699786004438, 15175.620609484851, 5840.737166827503, 5841.883162336814, 5965.224520812353, 5970.820875373745, 6004.819855404345, 1030.8299130643509, 1031.2265800649513, 1031.4481752262654, 1049.7638031442864, 1049.7952899184586, 23632.0, 23878.761212388003, 23934.64098038531, 24127.276181714577, 24192.758072419052, 1136.846525719034, 1145.410113791398, 1149.1324050341939, 1150.108665784024, 1160.5879912630894, 12651.557195498297, 12652.304123273309, 12808.871911280954, 12817.295048772221, 12895.484180632857, 225004.0, 225721.0, 226358.0, 226652.0, 227065.0, 55632.443675562725, 55741.72129139328, 55840.441595584336, 55934.72032639811, 55983.0, 1458.183417286323, 1517.597836573309, 1521.68805394896, 1522.1856306875768, 1540.5686407805833, 23455.76544234604, 23626.409339766644, 23664.408389790395, 23669.881650591637, 23880.88059559691, 38539.42190867083, 38551.22897541977, 38616.80691596524, 38711.6128838708, 38824.223515530095, 184854.0, 185024.0, 185068.0, 185219.0, 185278.0, 35289.64710352966, 35467.645323546436, 35473.82263088668, 37491.2501749958, 37521.06197344979, 45990.540094598626, 47820.760896195294, 48441.75779121082, 48917.755411222715, 49162.75418622884, 34699.47950780791, 34963.0, 35053.29893402169, 35129.64870351366, 35542.289154216254, 33743.83128084344, 34871.47692784661, 34949.65050349566, 35183.824080880455, 35604.287914242086, 36573.26853462969, 37540.81229593835, 37924.24151517009, 37946.24107517889, 38367.42448863213, 33261.50107748337, 33316.33367332591, 33339.66660333366, 33561.49657755087, 33969.32061358808, 25392.238232853044, 25448.872755636105, 25461.490770184864, 25490.362740931625, 25772.8711356442], "value": 17618.1557992347, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.277670135629, 585.8388943040663, 586.3286536915232, 589.7051474262869, 595.6396380189985, 22358.0, 22610.660840087392, 23537.64693529596, 23557.175498857545, 23558.17546385877, 26547.734522654766, 26927.865360673168, 27312.726872731266, 27317.863410682967, 27340.316492087706, 6551.344865513458, 6693.866122677544, 6720.3951644352, 6721.4286785623035, 6721.529492935497, 6068.514518838482, 6497.0, 6648.734050637969, 6655.700493477805, 6658.933410665904, 6614.801555953329, 6619.0, 6621.93378066218, 6624.403803657669, 6642.800715978528, 6590.044443555682, 6592.077109204692, 6612.86774264512, 6624.768133115318, 6636.070950066995, 6176.629402235881, 6177.969110154466, 6178.876422471525, 6182.752689892399, 6193.752249909998, 30647.54028689549, 30807.845960770275, 31459.52810707841, 32241.83879080613, 32340.353192936243, 1955.687090065597, 1993.8703984240979, 2000.1699294792643, 2001.7497812773404, 2003.1887085730302, 18263.63472730538, 18855.34006309786, 19186.808131918642, 19226.423207303804, 19391.80608193928, 2283.6574513822893, 2340.566995105911, 2341.789238968484, 2380.5595964746576, 2389.462370966536, 141665.29167354098, 146332.0, 147742.0, 148477.0, 148849.0, 14511.419543218364, 15048.849511504965, 15177.544673659588, 15531.767023494656, 15808.683826323639, 24067.51864962691, 24286.14998475045, 24311.0, 24314.87842560776, 24368.512629747307, 10252.23108266883, 10592.629257975934, 10639.148868090535, 10639.946800265949, 10662.520186591622, 8880.777980550409, 9771.804563908681, 9853.50732463374, 9922.702318930444, 10269.537870795832, 8323.417360784686, 9368.672096476726, 9548.665796697082, 9559.474228917337, 9612.567434465465, 2730.0924515391043, 2791.1068458093214, 2791.6091747155415, 2800.705925877794, 2822.8729707163225, 952.3809523809441, 963.9759006024765, 970.3935040599625, 975.6829030565095, 992.4591097851688, 20544.58910821775, 20835.895820521102, 21359.67960480594, 24631.0, 26139.0, 1037.4968140451874, 1040.2770074798093, 1054.2040759226888, 1061.5223149582719, 1064.0689396777818, 11538.0, 11921.761564768656, 11939.164258501994, 11990.820137697941, 12096.697582560506, 494889.0, 497432.0, 502879.0, 513177.0, 513617.0, 116004.0, 118034.0, 121950.0, 123887.0, 124681.37659311645, 1253.0602048463652, 1281.551456990046, 1298.5130576033987, 1298.5195477673321, 1301.5704817410042, 18717.59402087939, 18870.24519019224, 19177.904110479358, 19244.807551924863, 19808.40574782759, 57851.0, 58481.0, 59183.704081479314, 59203.40796591979, 61277.38722612717, 74486.0, 74640.0, 74960.0, 75093.62453187918, 75146.24853751392, 12681.23912565249, 13400.597982060553, 14750.778738319148, 14847.183404912834, 15263.160526171167, 24034.27897163088, 24353.51292974166, 24446.26661200167, 24547.877260613583, 24625.630615540424, 17884.91057544704, 18683.346082887034, 19042.904785475985, 19078.236870525023, 19096.904515477334, 17084.914575427043, 18121.275148993893, 18337.266509339475, 18345.0, 18991.525211869815, 20074.698879516527, 20200.797992019892, 20479.07844147047, 20771.169153233703, 20928.267510637055, 14672.413103475741, 14718.77921831152, 14729.116253024853, 14796.926015370285, 14835.629109272355, 18935.71596426027, 18983.24067037303, 19097.14062867202, 19221.711674325175, 19357.0], "value": 12382.164733515552, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1330.1885849631726, 1366.897482688798, 1373.9175649461033, 1385.5912505810784, 1400.5938277899397, 13356.532521361754, 14069.366878490468, 14070.4371825127, 14083.577492675211, 14086.506972255973, 13528.93235533821, 14025.64935876601, 14476.13143211408, 14506.782398264035, 14531.055481393701, 2754.2701184186158, 2800.187945495804, 2800.4679110968955, 2801.0, 2802.7337402946687, 2665.6134860445222, 2676.157010541673, 2706.512827691015, 2707.9729202707917, 2709.282040259333, 2673.679158500973, 2730.4539092181544, 2784.554471284595, 2786.205931309577, 2788.2332358601343, 2695.043259642828, 2699.055330634272, 2710.864456777173, 2711.6745990481168, 2711.8372897626205, 2671.919842404731, 2683.490136873988, 2685.583734521133, 2688.4891870544534, 2688.973110268892, 13510.93244533771, 13765.587032389045, 13767.586972390844, 13802.723945521133, 13827.170369777845, 7598.392128629696, 7912.050553933507, 7917.485363451357, 7920.366370690331, 7923.168067352903, 4984.551390374883, 5205.063088644042, 5284.682919024871, 5355.7857685692525, 5356.491133342326, 1685.5701796042054, 1712.725963845791, 1727.06738361286, 1753.833385828357, 1759.0589034866414, 22159.335219943427, 22370.66444003341, 22370.88814555933, 22473.213437529772, 22489.100435982706, 6350.5554611177695, 6543.345665433514, 6570.77002304917, 6722.899156512656, 6762.053312536249, 15559.45541906028, 15635.452759153375, 15660.608484787972, 15661.765073523906, 15661.843381566267, 7508.774736757906, 7656.693732250647, 7658.693652253847, 7684.231576842271, 7691.730789422343, 5843.824685259449, 6115.755369785248, 6227.657478838617, 6261.029540421242, 6410.326915673881, 5071.112555302807, 5143.897122057613, 5269.1569348903995, 5284.101702710548, 5308.389535203501, 1456.6722487440454, 1498.820141583017, 1503.7669161280019, 1547.156799544251, 1562.523430353753, 908.6002159050129, 919.296737995434, 921.6774129054911, 931.2317338196016, 938.3666025432806, 7554.848903021909, 7573.545587264782, 7590.316871481593, 7662.0, 7677.424193185533, 713.62534669298, 738.5642470942063, 743.4015617428022, 986.6546708651914, 1020.3265844542715, 5071.2139618359215, 5099.18413053917, 5316.043112239757, 5357.919631205535, 5484.7531861066345, 17892.642147156985, 18225.0, 18933.715994260096, 19019.1441385138, 19049.238030478624, 18486.0, 18657.533561660795, 18704.345347912757, 18769.436916892515, 18878.811211887707, 9876.061774131445, 10070.647527336509, 10239.385636861813, 10400.531976061247, 10499.842502362471, 8025.117237104031, 8321.209485098905, 8346.791330216793, 8365.874511882448, 8380.41337106421, 16028.278727457526, 16655.666886662442, 16846.15769211559, 17001.829981700026, 17235.396761113803, 16583.66832663316, 16799.496015119566, 17240.568985775455, 17370.565735856704, 17509.38717144945, 10904.291221070605, 11225.494852731483, 11252.887471125185, 11254.437278135896, 11258.493367798632, 12623.747525049632, 12905.419256133684, 13025.609231723063, 13251.66870828237, 13310.733785324433, 9907.752306192402, 10065.345752526355, 10069.597216111273, 10233.590656373959, 10506.947465262625, 10521.736956576147, 10531.57873685044, 10545.367277963347, 10811.459427028774, 10875.347479151276, 9375.906240937777, 9571.569279382584, 9586.137247647745, 9588.56851441673, 9593.232541396532, 7219.0976127984, 7232.493725439169, 7305.853882922418, 7306.853862922818, 7314.561126332437, 7268.0, 7335.889961650473, 7442.702291908263, 7498.775036748906, 7639.541627502367], "value": 6336.113007023613, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.7583152414215, 513.2250302043913, 525.5926656840948, 531.5800517591103, 543.858596764841, 6171.228596425447, 6250.937490625098, 6252.531060170489, 6266.0, 6329.873402531958, 6068.787592434266, 6204.224471941007, 6214.782482613109, 6220.253569571658, 6226.906596401046, 5133.974330128344, 5158.587313014949, 5160.6903585784785, 5183.17069268917, 5196.168613021917, 4830.9275360869615, 4949.257611358289, 5101.7194054327, 5109.386873575176, 5154.252633368159, 4899.191633380488, 4938.678985865925, 4943.678660887049, 4946.554810067093, 4951.975240123795, 4943.134951383494, 4944.381952255968, 4962.900741985176, 4974.129527332702, 4978.103941290567, 4453.621442177387, 4461.709988850715, 4465.59809617136, 4469.82120715171, 4472.351509031205, 5859.648421094706, 6056.69716514177, 6065.818025459199, 6067.848303792396, 6092.0453580013655, 5625.46558076982, 5697.487226149625, 5707.800226992077, 5737.9713101434645, 5800.941990580082, 6529.233659428596, 6539.378759017885, 6570.671466426659, 6576.86846263072, 6588.703508342136, 4868.245421959602, 4880.097182021302, 4895.804167833282, 4921.917178220786, 4930.654854160211, 6593.911774699669, 6690.297518760557, 6762.087118239024, 6815.488838337095, 6913.170419549636, 4953.479884612136, 4957.702537847668, 4978.660029786067, 4991.3511243538105, 5023.49765023502, 5056.974715126401, 5066.898662026739, 5076.213186956028, 5096.286519887219, 5106.974465127725, 5706.0, 5717.0, 5724.112762521816, 5730.997075511768, 5759.1937128802, 6598.901016484757, 6617.636030018299, 6630.86738265232, 6639.203295604559, 6717.832054198684, 1750.7986581543285, 1768.2131451504215, 1771.4419957713278, 1773.5743421578986, 1774.5918438759159, 1144.3820337018083, 1198.7722332756748, 1203.2479700187382, 1224.9571265005861, 1234.2039384597065, 5759.164921086419, 5810.94189058104, 5811.302643682785, 5822.272215973004, 5829.5044921181325, 6456.257530383972, 6634.502412319095, 6635.170603674541, 6681.231658359351, 6691.431228345547, 5713.428657134254, 5745.052066409079, 5793.565482588822, 5797.159411885253, 5827.038538641162, 3556.772913344892, 3594.0, 3605.4591811228524, 3610.891673249807, 3628.38317486028, 6584.473242140617, 6622.602643841385, 6640.435562977104, 6641.0, 6648.069270302161, 6937.9653101735175, 6939.20199177091, 6970.268121847234, 6989.685464154024, 7040.683169257396, 6545.70544325516, 6559.8032059038305, 6568.80293591193, 6615.801525954229, 6627.436667883284, 6689.76585819494, 6725.529212955046, 6739.292374300628, 6752.223494298219, 6756.358145976124, 6534.83662908412, 6665.0, 6675.532712710064, 6731.225909020525, 6737.831554210988, 6521.8695626088165, 6548.476121910331, 6565.671716414256, 6570.8357291066195, 6602.7689030883685, 4915.975420122876, 5007.599392048554, 5012.3233363495465, 5025.0954828129825, 5054.82308119214, 6523.347665233438, 6527.51043671736, 6735.73057077731, 6843.931560684329, 6845.589264644137, 6455.837949169195, 6487.956293767448, 6500.219973603198, 6501.187351581052, 6515.6742162892615, 6473.676316184266, 6475.870482590228, 6503.674816259263, 6543.378379053982, 6554.492466732773, 6124.755009799558, 6396.200474940632, 6425.518086143651, 6456.451201647913, 6481.578697384655, 6493.155889734304, 6508.967455162694, 6567.179102612174, 6569.540132190701, 6640.402363787282, 6355.173827402408, 6397.840053998687, 6435.871282574416, 6441.452476539546, 6442.130312407765], "value": 5069.813436886441, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [890.4434728294816, 901.7384958362074, 914.7575892388522, 920.4431319051973, 923.8937522184947, 18200.180991855366, 18801.0, 18817.62364752706, 18884.905575472105, 20714.27500037499, 16657.666846663058, 16705.83294167061, 18489.7226541602, 21467.785322146774, 21568.460788480297, 1028.9176865850732, 1049.9527521261523, 1051.458498873081, 1051.831706926892, 1051.8895515970828, 1058.8041212375736, 1062.537796058713, 1062.904338609525, 1063.0326402973296, 1064.0636240108693, 1056.6407421476697, 1093.3276035238312, 1104.4753741972538, 1109.5949978257918, 1117.8938000889902, 1080.778440419712, 1081.7241603391124, 1087.9510422031028, 1092.20814909191, 1092.781443711257, 799.3485309472819, 800.2677550041705, 800.6037011679256, 803.0203152154362, 803.8271771569141, 19385.709214361796, 19445.708314375297, 19731.90134049335, 19786.60426791456, 20771.37685869411, 20567.0, 20858.269960551308, 21170.788292117035, 21185.258515951868, 21326.146954121818, 15812.209389530593, 15872.52382428529, 15908.045517268885, 15950.521484355371, 16116.83883161165, 3460.688538031564, 3501.91245218869, 3502.8949131526097, 3509.0525558099425, 3512.5609298837644, 129726.0, 130620.0, 131652.0, 134948.32525837407, 136353.3182334092, 15501.0, 15690.529284121494, 15863.286152122952, 15989.200539972955, 16005.359785608445, 30315.545266820573, 30458.54312185319, 30476.390472190433, 30494.84752576223, 31520.211994699857, 8542.530160841214, 8601.182887625666, 8647.697330593524, 8680.305575554066, 8697.652093916173, 4282.079352939133, 4288.699791014599, 4332.631726303236, 4368.191884501314, 4396.692231543761, 5285.9735701322015, 5340.412554618989, 5382.4886635769535, 5390.245365648812, 5419.078756611386, 955.7085089047769, 981.097390400833, 995.7311525887969, 998.2612866478898, 998.5805961496189, 639.2488825629866, 656.3043174235326, 658.9637569933603, 665.3280187011056, 677.5731289287717, 11275.436228188557, 11573.42132893352, 11698.590549330733, 12668.493260269486, 13707.314634268247, 678.1997243253008, 686.6189264957897, 689.931006899306, 693.6011793218919, 698.6192525073745, 5362.276092727432, 5494.945050549523, 5514.924589704969, 5560.138178582326, 5602.159676048544, 101968.49015754873, 103137.48431257796, 104948.47525762323, 105426.0, 109764.4511777436, 91776.0, 91995.0, 92487.0, 92793.0, 93251.5337423322, 83962.5801870999, 84311.15688843156, 84448.57775711082, 85174.57412712897, 85833.0, 11717.355545445082, 11846.348450835287, 11970.76058478843, 12000.699982500508, 12026.338551379758, 35547.28905421929, 37330.62669373272, 37833.62166378301, 38366.616333837424, 38798.0, 65070.0, 66225.0, 67261.32738672747, 67645.0, 70264.29735702577, 18614.627707445503, 19023.714644280622, 19075.713864292324, 19241.5189620255, 19384.612307753483, 18891.716624250374, 18937.147828347483, 19133.0, 19472.805271947098, 20221.191152353742, 18128.184231709867, 18147.73826259501, 18156.0, 18250.0, 18456.538586534905, 17616.383426580007, 17626.0, 17874.374396896048, 18619.16213770411, 18723.97018164014, 17881.73177402366, 18481.53796155107, 19071.90464047671, 19135.0, 19148.234070637576, 12814.935925320313, 13685.794713079113, 13940.512082077485, 14021.789673155115, 14394.784078239045, 13456.932715336361, 13617.182969021891, 13878.306084695523, 13938.372773225432, 14000.0], "value": 9104.189687307553, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1539.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 107.0, 103.0, 109.0, 111.0, 111.0, 109.0, 112.0, 112.0, 111.0], "value": 108.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 117.0, 141.0, 165.0, 117.0, 141.0, 154.0, 119.0, 182.0, 119.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 171.0, 162.0, 163.0, 163.0, 171.0, 167.0, 163.0, 169.0], "value": 165.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 74.0, 161.0, 80.0, 76.0, 78.0, 89.0, 100.0, 74.0], "value": 88.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 138.0, 79.0, 79.0, 78.0, 154.0, 79.0, 77.0, 126.0], "value": 96.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 117.0, 117.0, 114.0, 114.0, 114.0, 117.0], "value": 114.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 153.0, 151.0, 150.0, 155.0, 154.0, 151.0, 151.0, 152.0, 153.0], "value": 152.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 101.0, 98.0, 94.0, 93.0, 99.0, 94.0, 97.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 56.0, 56.0, 61.0, 56.0, 57.0, 55.0, 57.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 54.0, 54.0, 61.0, 54.0, 53.0, 54.0, 60.0, 55.0], "value": 55.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 87.0, 76.0, 88.0, 76.0, 86.0, 81.0, 87.0, 87.0, 78.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 136.0, 133.0, 131.0, 132.0, 126.0, 131.0, 135.0, 133.0, 173.0], "value": 136.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 181.0, 181.0, 195.0, 174.0, 175.0, 173.0, 179.0, 174.0, 176.0], "value": 178.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 66.0, 68.0, 71.0, 67.0, 69.0, 66.0, 71.0], "value": 67.8, "unit": "score"}]}]} 16:55:17 INFO - Return code: 0 16:55:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:55:17 INFO - # TBPL SUCCESS # 16:55:17 INFO - Running post-action listener: _package_coverage_data 16:55:17 INFO - Running post-action listener: _resource_record_post_action 16:55:17 INFO - [mozharness: 2017-08-02 23:55:17.212165Z] Finished run-tests step (success) 16:55:17 INFO - Running post-run listener: _resource_record_post_run 16:55:17 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 5836800; Write bytes: 442847232; Read time: 1984; Write time: 395764 16:55:17 INFO - TinderboxPrint: CPU usage
13.2% 16:55:17 INFO - TinderboxPrint: I/O read bytes / time
5,836,800 / 1,984 16:55:17 INFO - TinderboxPrint: I/O write bytes / time
442,847,232 / 395,764 16:55:17 INFO - TinderboxPrint: CPU idle
8,284.8 (86.8%) 16:55:17 INFO - TinderboxPrint: CPU user
1,205.5 (12.6%) 16:55:17 INFO - TinderboxPrint: Swap in / out
0 / 0 16:55:17 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 119828480; Read time: 0; Write time: 114536 16:55:17 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:55:17 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 3469312; Write bytes: 323018752; Read time: 1548; Write time: 281228 16:55:17 INFO - Running post-run listener: _upload_blobber_files 16:55:17 INFO - Blob upload gear active. 16:55:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:55:17 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:55:17 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:55:17 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:56:18 INFO - Traceback (most recent call last): 16:56:18 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 253, in 16:56:18 INFO - main() 16:56:18 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 235, in main 16:56:18 INFO - filetype_whitelist = get_server_whitelist(args['--url']) 16:56:18 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 69, in get_server_whitelist 16:56:18 INFO - return set(response.json().get('whitelist', [])) 16:56:18 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/models.py", line 651, in json 16:56:18 INFO - return json.loads(self.text or self.content, **kwargs) 16:56:18 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/__init__.py", line 488, in loads 16:56:18 INFO - return _default_decoder.decode(s) 16:56:18 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 370, in decode 16:56:18 INFO - obj, end = self.raw_decode(s) 16:56:18 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/simplejson/decoder.py", line 389, in raw_decode 16:56:18 INFO - return self.scan_once(s, idx=_w(s, idx).end()) 16:56:18 INFO - simplejson.scanner.JSONDecodeError: Expecting value: line 1 column 1 (char 0) 16:56:18 ERROR - Return code: 1 16:56:18 INFO - Setting buildbot property blobber_files to {} 16:56:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:56:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:56:18 INFO - Contents: 16:56:18 INFO - blobber_files:{} 16:56:18 INFO - Running post-run listener: copy_logs_to_upload_dir 16:56:18 INFO - Copying logs to upload dir... 16:56:18 INFO - mkdir: /builds/slave/test/build/upload/logs 16:56:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1312.440578 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 52 secs) (at 2017-08-02 16:56:18.259537) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-02 16:56:18.263804) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{} build_url:https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011129 build_url: 'https://queue.taskcluster.net/v1/task/exfe1HmBRcWAzUMI2Oc6Pg/artifacts/public/build/target.tar.bz2' blobber_files: '{}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-02 16:56:18.316490) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 16:56:18.316848) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1501704420.478548-83231284 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004385 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 16:56:18.371303) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-02 16:56:18.373662) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-02 16:56:18.373959) ========= ========= Total master_lag: 0.23 =========