builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-043 starttime: 1503010896.71 results: success (0) revision: 54ad22b649e547a5b20babdecc84f5563a9b7dea ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-17 16:01:36.706534) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-17 16:01:36.706959) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-17 16:01:36.707229) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-17 16:01:36.732464) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-17 16:01:36.732721) ========= 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=d2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-17 16:01:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2017-08-17 16:01:37 (223 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.177615 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-17 16:01:36.935715) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-17 16:01:36.936038) ========= 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=d2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016441 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-17 16:01:36.984896) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-17 16:01:36.985194) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 54ad22b649e547a5b20babdecc84f5563a9b7dea --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 54ad22b649e547a5b20babdecc84f5563a9b7dea --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=d2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-17 16:01:37,158 truncating revision to first 12 chars 2017-08-17 16:01:37,158 Setting DEBUG logging. 2017-08-17 16:01:37,158 attempt 1/10 2017-08-17 16:01:37,158 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/54ad22b649e5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-17 16:01:38,004 attempt 1/10 2017-08-17 16:01:38,625 current task status: no status available at this point. state: PENDING 2017-08-17 16:01:38,625 sleeping for 10.00s (attempt 1/10) 2017-08-17 16:01:48,635 attempt 2/10 2017-08-17 16:01:48,974 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-17 16:01:49,184 unpacking tar archive at: mozilla-central-54ad22b649e5/testing/mozharness/ program finished with exit code 0 elapsedTime=12.318666 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-17 16:01:49.324783) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-17 16:01:49.325106) ========= script_repo_revision: 54ad22b649e547a5b20babdecc84f5563a9b7dea ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-17 16:01:49.325495) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-17 16:01:49.325758) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-17 16:01:49.337661) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-08-17 16:01:49.337949) ========= /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=d2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:01:49 INFO - MultiFileLogger online at 20170817 16:01:49 in /builds/slave/test 16:01:49 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:01:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:01:49 INFO - {'append_to_log': False, 16:01:49 INFO - 'base_work_dir': '/builds/slave/test', 16:01:49 INFO - 'blob_upload_branch': 'Firefox', 16:01:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:01:49 INFO - 'branch': 'Firefox', 16:01:49 INFO - 'buildbot_json_path': 'buildprops.json', 16:01:49 INFO - 'code_coverage': False, 16:01:49 INFO - 'config_files': ('talos/linux_config.py',), 16:01:49 INFO - 'default_actions': ('clobber', 16:01:49 INFO - 'read-buildbot-config', 16:01:49 INFO - 'download-and-extract', 16:01:49 INFO - 'populate-webroot', 16:01:49 INFO - 'create-virtualenv', 16:01:49 INFO - 'install', 16:01:49 INFO - 'setup-mitmproxy', 16:01:49 INFO - 'run-tests'), 16:01:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:01:49 INFO - 'disable_ccov_upload': False, 16:01:49 INFO - 'download_minidump_stackwalk': True, 16:01:49 INFO - 'download_symbols': 'ondemand', 16:01:49 INFO - 'enable_stylo': False, 16:01:49 INFO - 'enable_webrender': False, 16:01:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:01:49 INFO - 'tooltool.py': '/tools/tooltool.py', 16:01:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:01:49 INFO - '/tools/misc-python/virtualenv.py')}, 16:01:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:01:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:01:49 INFO - 'gecko_profile': False, 16:01:49 INFO - 'gecko_profile_interval': 0, 16:01:49 INFO - 'installer_path': 'installer.exe', 16:01:49 INFO - 'log_level': 'info', 16:01:49 INFO - 'log_name': 'talos', 16:01:49 INFO - 'log_to_console': True, 16:01:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:01:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:01:49 INFO - 'opt_config_files': (), 16:01:49 INFO - 'pip_index': False, 16:01:49 INFO - 'suite': 'dromaeojs-e10s', 16:01:49 INFO - 'system_bits': '32', 16:01:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:01:49 INFO - 'title': 'talos-linux64-ix-043', 16:01:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:01:49 INFO - 'use_talos_json': True, 16:01:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:01:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:01:49 INFO - 'work_dir': 'build'} 16:01:49 INFO - [mozharness: 2017-08-17 23:01:49.713375Z] Running clobber step. 16:01:49 INFO - Running pre-action listener: _resource_record_pre_action 16:01:49 INFO - Running main action method: clobber 16:01:49 INFO - rmtree: /builds/slave/test/build 16:01:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:01:50 INFO - Running post-action listener: _resource_record_post_action 16:01:50 INFO - [mozharness: 2017-08-17 23:01:50.691844Z] Finished clobber step (success) 16:01:50 INFO - [mozharness: 2017-08-17 23:01:50.691946Z] Running read-buildbot-config step. 16:01:50 INFO - Running pre-action listener: _resource_record_pre_action 16:01:50 INFO - Running main action method: read_buildbot_config 16:01:50 INFO - Using buildbot properties: 16:01:50 INFO - { 16:01:50 INFO - "project": "", 16:01:50 INFO - "product": "firefox", 16:01:50 INFO - "who": "dtownsend@mozilla.com", 16:01:50 INFO - "installer_path": "public/build/target.tar.bz2", 16:01:50 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 16:01:50 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:01:50 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 16:01:50 INFO - "stage_platform": "linux64-stylo", 16:01:50 INFO - "basedir": "/builds/slave/test", 16:01:50 INFO - "buildnumber": 274, 16:01:50 INFO - "platform": "ubuntu64_hw_stylo", 16:01:50 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:01:50 INFO - "slavebuilddir": "test", 16:01:50 INFO - "taskId": "KsndmA9NRueAAOsyTW7ucw", 16:01:50 INFO - "branch": "mozilla-central", 16:01:50 INFO - "script_repo_revision": "production", 16:01:50 INFO - "revision": "54ad22b649e547a5b20babdecc84f5563a9b7dea", 16:01:50 INFO - "slavename": "talos-linux64-ix-043", 16:01:50 INFO - "repo_path": "mozilla-central" 16:01:50 INFO - } 16:01:50 INFO - Finding installer, test and symbols from parent task. 16:01:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KsndmA9NRueAAOsyTW7ucw'}, attempt #1 16:01:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KsndmA9NRueAAOsyTW7ucw'}, attempt #1 16:01:51 INFO - Task dependencies: aWkJAhYOSgqx_drvisgeVA 16:01:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA'}, attempt #1 16:01:52 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.tar.bz2 16:01:52 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.test_packages.json 16:01:52 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.crashreporter-symbols.zip 16:01:52 INFO - Running post-action listener: _resource_record_post_action 16:01:52 INFO - [mozharness: 2017-08-17 23:01:52.956651Z] Finished read-buildbot-config step (success) 16:01:52 INFO - [mozharness: 2017-08-17 23:01:52.956874Z] Running download-and-extract step. 16:01:52 INFO - Running pre-action listener: _resource_record_pre_action 16:01:52 INFO - Running main action method: download_and_extract 16:01:52 INFO - mkdir: /builds/slave/test/build/tests 16:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:01:52 INFO - trying https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.test_packages.json 16:01:52 INFO - Downloading https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:01:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:01:53 INFO - Downloaded 989 bytes. 16:01:53 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:01:53 INFO - Using the following test package requirements: 16:01:53 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:01:53 INFO - u'common': [u'target.common.tests.zip'], 16:01:53 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:01:53 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:01:53 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:01:53 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:01:53 INFO - u'mozbase': [u'target.common.tests.zip'], 16:01:53 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:01:53 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:01:53 INFO - u'web-platform': [u'target.common.tests.zip', 16:01:53 INFO - u'target.web-platform.tests.tar.gz'], 16:01:53 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:01:53 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:01:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.common.tests.zip 16:01:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:01:53 INFO - Fetch https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.common.tests.zip into memory 16:01:54 INFO - Content-Length response header: 53999336 16:01:54 INFO - Bytes received: 53999336 16:02:05 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:02:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.common.tests.zip 16:02:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:02:05 INFO - Fetch https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.common.tests.zip into memory 16:02:07 INFO - Content-Length response header: 53999336 16:02:07 INFO - Bytes received: 53999336 16:02:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.talos.tests.zip 16:02:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:02:20 INFO - Fetch https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.talos.tests.zip into memory 16:02:21 INFO - Content-Length response header: 14496161 16:02:21 INFO - Bytes received: 14496161 16:02:21 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:02:21 INFO - trying https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.tar.bz2 16:02:21 INFO - Downloading https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:02:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:02:22 INFO - Downloaded 62367576 bytes. 16:02:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.tar.bz2 16:02:22 INFO - mkdir: /builds/slave/test/properties 16:02:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:02:22 INFO - Writing to file /builds/slave/test/properties/build_url 16:02:22 INFO - Contents: 16:02:22 INFO - build_url:https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.tar.bz2 16:02:22 INFO - Running post-action listener: _resource_record_post_action 16:02:22 INFO - Running post-action listener: set_extra_try_arguments 16:02:22 INFO - [mozharness: 2017-08-17 23:02:22.583641Z] Finished download-and-extract step (success) 16:02:22 INFO - [mozharness: 2017-08-17 23:02:22.583739Z] Running populate-webroot step. 16:02:22 INFO - Running pre-action listener: _resource_record_pre_action 16:02:22 INFO - Running main action method: populate_webroot 16:02:22 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:02:22 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 16:02:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:02:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:02:22 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 16:02:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:02:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:02:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:02:22 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:02:22 INFO - 'talos_options': ['--stylo'], 16:02:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:02:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:02:22 INFO - 'tests': ['damp', 'tps']}, 16:02:22 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:02:22 INFO - 'talos_options': ['--stylo'], 16:02:22 INFO - 'tests': ['damp', 'tps']}, 16:02:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:02:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:02:22 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 16:02:22 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:02:22 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:02:22 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:02:22 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:02:22 INFO - 'talos_options': ['--stylo'], 16:02:22 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:02:22 INFO - 'other-e10s': {'tests': ['a11yr', 16:02:22 INFO - 'ts_paint', 16:02:22 INFO - 'tpaint', 16:02:22 INFO - 'sessionrestore', 16:02:22 INFO - 'sessionrestore_many_windows', 16:02:22 INFO - 'sessionrestore_no_auto_restore', 16:02:22 INFO - 'tabpaint']}, 16:02:22 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 16:02:22 INFO - 'tests': ['a11yr', 16:02:22 INFO - 'ts_paint', 16:02:22 INFO - 'tpaint', 16:02:22 INFO - 'sessionrestore', 16:02:22 INFO - 'sessionrestore_many_windows', 16:02:22 INFO - 'sessionrestore_no_auto_restore', 16:02:22 INFO - 'tabpaint']}, 16:02:22 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:02:22 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:02:22 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 16:02:22 INFO - 'tests': ['bloom_basic_singleton']}, 16:02:22 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 16:02:22 INFO - 'tests': ['bloom_basic']}, 16:02:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:02:22 INFO - 'tsvgr_opacity', 16:02:22 INFO - 'tart', 16:02:22 INFO - 'tscrollx', 16:02:22 INFO - 'tsvg_static']}, 16:02:22 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 16:02:22 INFO - 'tests': ['tsvgx', 16:02:22 INFO - 'tsvgr_opacity', 16:02:22 INFO - 'tart', 16:02:22 INFO - 'tscrollx', 16:02:22 INFO - 'tsvg_static']}, 16:02:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:02:22 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:02:22 INFO - 'talos_options': ['--stylo'], 16:02:22 INFO - 'tests': ['tp5o']}, 16:02:22 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:02:22 INFO - 'talos_options': ['--mitmproxy', 16:02:22 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:02:22 INFO - '--firstNonBlankPaint'], 16:02:22 INFO - 'tests': ['tp6_google', 16:02:22 INFO - 'tp6_youtube', 16:02:22 INFO - 'tp6_amazon', 16:02:22 INFO - 'tp6_facebook']}, 16:02:22 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:02:22 INFO - 'talos_options': ['--stylo', 16:02:22 INFO - '--mitmproxy', 16:02:22 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:02:22 INFO - '--firstNonBlankPaint'], 16:02:22 INFO - 'tests': ['tp6_google', 16:02:22 INFO - 'tp6_youtube', 16:02:22 INFO - 'tp6_amazon', 16:02:22 INFO - 'tp6_facebook']}, 16:02:22 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:02:22 INFO - 'talos_options': ['--stylo', 16:02:22 INFO - '--stylo-threads=1', 16:02:22 INFO - '--mitmproxy', 16:02:22 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:02:22 INFO - '--firstNonBlankPaint'], 16:02:22 INFO - 'tests': ['tp6_google', 16:02:22 INFO - 'tp6_youtube', 16:02:22 INFO - 'tp6_amazon', 16:02:22 INFO - 'tp6_facebook']}, 16:02:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:02:22 INFO - 'talos_options': ['--xperf_path', 16:02:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:02:22 INFO - 'tests': ['tp5n']}, 16:02:22 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:02:22 INFO - 'talos_options': ['--stylo', 16:02:22 INFO - '--xperf_path', 16:02:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:02:22 INFO - 'tests': ['tp5n']}}} 16:02:22 INFO - Running post-action listener: _resource_record_post_action 16:02:22 INFO - [mozharness: 2017-08-17 23:02:22.597851Z] Finished populate-webroot step (success) 16:02:22 INFO - [mozharness: 2017-08-17 23:02:22.597940Z] Running create-virtualenv step. 16:02:22 INFO - Running pre-action listener: _resource_record_pre_action 16:02:22 INFO - Running main action method: create_virtualenv 16:02:22 INFO - Creating virtualenv /builds/slave/test/build/venv 16:02:22 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:02:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:02:22 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:02:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:02:22 INFO - Using real prefix '/usr' 16:02:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:02:23 INFO - Installing distribute.............................................................................................................................................................................................done. 16:02:25 INFO - Installing pip.................done. 16:02:25 INFO - Return code: 0 16:02:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:02:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:02:25 INFO - Reading from file tmpfile_stdout 16:02:25 INFO - Output received: 16:02:25 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:02:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:02:25 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:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:25 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:02:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:25 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 0x1afd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da20b0>, '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 0x1da2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da3440>, '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': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', '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:02:25 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:02:25 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:02:25 INFO - Using env: {'DISPLAY': ':0', 16:02:25 INFO - 'HOME': '/home/cltbld', 16:02:25 INFO - 'LANG': 'en_US.UTF-8', 16:02:25 INFO - 'LANGUAGE': 'en_US:en', 16:02:25 INFO - 'LOGNAME': 'cltbld', 16:02:25 INFO - 'MAIL': '/var/mail/cltbld', 16:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:25 INFO - 'MOZ_NO_REMOTE': '1', 16:02:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:25 INFO - 'NO_EM_RESTART': '1', 16:02:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:25 INFO - 'PWD': '/builds/slave/test', 16:02:25 INFO - 'SHELL': '/bin/bash', 16:02:25 INFO - 'SHLVL': '1', 16:02:25 INFO - 'TERM': 'linux', 16:02:25 INFO - 'TMOUT': '86400', 16:02:25 INFO - 'USER': 'cltbld', 16:02:25 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', 16:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:25 INFO - '_': '/tools/buildbot/bin/python'} 16:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:25 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:02:25 INFO - Cleaning up... 16:02:25 INFO - Return code: 0 16:02:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:02:25 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:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:25 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:02:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:25 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 0x1afd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da20b0>, '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 0x1da2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da3440>, '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': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', '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:02:25 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:02:25 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:02:25 INFO - Using env: {'DISPLAY': ':0', 16:02:25 INFO - 'HOME': '/home/cltbld', 16:02:25 INFO - 'LANG': 'en_US.UTF-8', 16:02:25 INFO - 'LANGUAGE': 'en_US:en', 16:02:25 INFO - 'LOGNAME': 'cltbld', 16:02:25 INFO - 'MAIL': '/var/mail/cltbld', 16:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:25 INFO - 'MOZ_NO_REMOTE': '1', 16:02:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:25 INFO - 'NO_EM_RESTART': '1', 16:02:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:25 INFO - 'PWD': '/builds/slave/test', 16:02:25 INFO - 'SHELL': '/bin/bash', 16:02:25 INFO - 'SHLVL': '1', 16:02:25 INFO - 'TERM': 'linux', 16:02:25 INFO - 'TMOUT': '86400', 16:02:25 INFO - 'USER': 'cltbld', 16:02:25 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', 16:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:25 INFO - '_': '/tools/buildbot/bin/python'} 16:02:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:26 INFO - Downloading/unpacking psutil>=3.1.1 16:02:26 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:02:26 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:02:26 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:02:26 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:02:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:02:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:02:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:02:27 INFO - Installing collected packages: psutil 16:02:27 INFO - Running setup.py install for psutil 16:02:27 INFO - building 'psutil._psutil_linux' extension 16:02:27 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:02:28 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:02:28 INFO - building 'psutil._psutil_posix' extension 16:02:28 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:02:28 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:02:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:02:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:02:28 INFO - Successfully installed psutil 16:02:28 INFO - Cleaning up... 16:02:28 INFO - Return code: 0 16:02:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:02: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:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02: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:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:28 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 0x1afd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da20b0>, '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 0x1da2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da3440>, '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': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', '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:02:28 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:02:28 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:02:28 INFO - Using env: {'DISPLAY': ':0', 16:02:28 INFO - 'HOME': '/home/cltbld', 16:02:28 INFO - 'LANG': 'en_US.UTF-8', 16:02:28 INFO - 'LANGUAGE': 'en_US:en', 16:02:28 INFO - 'LOGNAME': 'cltbld', 16:02:28 INFO - 'MAIL': '/var/mail/cltbld', 16:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:28 INFO - 'MOZ_NO_REMOTE': '1', 16:02:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:28 INFO - 'NO_EM_RESTART': '1', 16:02:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:28 INFO - 'PWD': '/builds/slave/test', 16:02:28 INFO - 'SHELL': '/bin/bash', 16:02:28 INFO - 'SHLVL': '1', 16:02:28 INFO - 'TERM': 'linux', 16:02:28 INFO - 'TMOUT': '86400', 16:02:28 INFO - 'USER': 'cltbld', 16:02:28 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', 16:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:28 INFO - '_': '/tools/buildbot/bin/python'} 16:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:02:28 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:02:28 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:02:28 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:02:28 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:02:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:02:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:02:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:02:30 INFO - Installing collected packages: mozsystemmonitor 16:02:30 INFO - Running setup.py install for mozsystemmonitor 16:02:30 INFO - Successfully installed mozsystemmonitor 16:02:30 INFO - Cleaning up... 16:02:30 INFO - Return code: 0 16:02:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:02:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:30 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 0x1afd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da20b0>, '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 0x1da2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da3440>, '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': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', '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:02:30 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:02:30 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:02:30 INFO - Using env: {'DISPLAY': ':0', 16:02:30 INFO - 'HOME': '/home/cltbld', 16:02:30 INFO - 'LANG': 'en_US.UTF-8', 16:02:30 INFO - 'LANGUAGE': 'en_US:en', 16:02:30 INFO - 'LOGNAME': 'cltbld', 16:02:30 INFO - 'MAIL': '/var/mail/cltbld', 16:02:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:30 INFO - 'MOZ_NO_REMOTE': '1', 16:02:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:30 INFO - 'NO_EM_RESTART': '1', 16:02:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:30 INFO - 'PWD': '/builds/slave/test', 16:02:30 INFO - 'SHELL': '/bin/bash', 16:02:30 INFO - 'SHLVL': '1', 16:02:30 INFO - 'TERM': 'linux', 16:02:30 INFO - 'TMOUT': '86400', 16:02:30 INFO - 'USER': 'cltbld', 16:02:30 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', 16:02:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:30 INFO - '_': '/tools/buildbot/bin/python'} 16:02:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:30 INFO - Downloading/unpacking jsonschema==2.5.1 16:02:30 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:02:30 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:02:30 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:02:30 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:02:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:02:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:02:31 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:02:31 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:02:31 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:02:31 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:02:31 INFO - Downloading functools32-3.2.3-2.tar.gz 16:02:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:02:31 INFO - warning: no files found matching '*.txt' 16:02:31 INFO - no previously-included directories found matching 'build' 16:02:31 INFO - no previously-included directories found matching 'dist' 16:02:31 INFO - no previously-included directories found matching '.git*' 16:02:31 INFO - Installing collected packages: jsonschema, functools32 16:02:31 INFO - Running setup.py install for functools32 16:02:31 INFO - warning: no files found matching '*.txt' 16:02:31 INFO - no previously-included directories found matching 'build' 16:02:31 INFO - no previously-included directories found matching 'dist' 16:02:31 INFO - no previously-included directories found matching '.git*' 16:02:31 INFO - Successfully installed jsonschema functools32 16:02:31 INFO - Cleaning up... 16:02:31 INFO - Return code: 0 16:02:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:02:31 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:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:31 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:02:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:31 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 0x1afd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da20b0>, '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 0x1da2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da3440>, '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': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', '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:02:31 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:02:31 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:02:31 INFO - Using env: {'DISPLAY': ':0', 16:02:31 INFO - 'HOME': '/home/cltbld', 16:02:31 INFO - 'LANG': 'en_US.UTF-8', 16:02:31 INFO - 'LANGUAGE': 'en_US:en', 16:02:31 INFO - 'LOGNAME': 'cltbld', 16:02:31 INFO - 'MAIL': '/var/mail/cltbld', 16:02:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:31 INFO - 'MOZ_NO_REMOTE': '1', 16:02:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:31 INFO - 'NO_EM_RESTART': '1', 16:02:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:31 INFO - 'PWD': '/builds/slave/test', 16:02:31 INFO - 'SHELL': '/bin/bash', 16:02:31 INFO - 'SHLVL': '1', 16:02:31 INFO - 'TERM': 'linux', 16:02:31 INFO - 'TMOUT': '86400', 16:02:31 INFO - 'USER': 'cltbld', 16:02:31 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', 16:02:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:31 INFO - '_': '/tools/buildbot/bin/python'} 16:02:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:02:32 INFO - Cleaning up... 16:02:32 INFO - Return code: 0 16:02:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:02:32 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:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:32 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:02:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:32 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 0x1afd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da20b0>, '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 0x1da2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da3440>, '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': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', '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:02:32 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:02:32 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:02:32 INFO - Using env: {'DISPLAY': ':0', 16:02:32 INFO - 'HOME': '/home/cltbld', 16:02:32 INFO - 'LANG': 'en_US.UTF-8', 16:02:32 INFO - 'LANGUAGE': 'en_US:en', 16:02:32 INFO - 'LOGNAME': 'cltbld', 16:02:32 INFO - 'MAIL': '/var/mail/cltbld', 16:02:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:32 INFO - 'MOZ_NO_REMOTE': '1', 16:02:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:32 INFO - 'NO_EM_RESTART': '1', 16:02:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:32 INFO - 'PWD': '/builds/slave/test', 16:02:32 INFO - 'SHELL': '/bin/bash', 16:02:32 INFO - 'SHLVL': '1', 16:02:32 INFO - 'TERM': 'linux', 16:02:32 INFO - 'TMOUT': '86400', 16:02:32 INFO - 'USER': 'cltbld', 16:02:32 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', 16:02:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:32 INFO - '_': '/tools/buildbot/bin/python'} 16:02:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:32 INFO - Downloading/unpacking blobuploader==1.2.4 16:02:32 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:02:32 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:02:32 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:02:32 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:02:33 INFO - Downloading blobuploader-1.2.4.tar.gz 16:02:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:02:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:02:33 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:02:33 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:02:33 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:02:33 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:02:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:02:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:02:33 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:02:33 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:02:33 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:02:33 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:02:34 INFO - Downloading docopt-0.6.1.tar.gz 16:02:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:02:34 INFO - Installing collected packages: blobuploader, requests, docopt 16:02:34 INFO - Running setup.py install for blobuploader 16:02:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:02:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:02:34 INFO - Running setup.py install for requests 16:02:34 INFO - Running setup.py install for docopt 16:02:34 INFO - Successfully installed blobuploader requests docopt 16:02:34 INFO - Cleaning up... 16:02:34 INFO - Return code: 0 16:02:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:34 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 0x1afd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da20b0>, '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 0x1da2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da3440>, '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': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', '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:02:34 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:02:34 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:02:34 INFO - Using env: {'DISPLAY': ':0', 16:02:34 INFO - 'HOME': '/home/cltbld', 16:02:34 INFO - 'LANG': 'en_US.UTF-8', 16:02:34 INFO - 'LANGUAGE': 'en_US:en', 16:02:34 INFO - 'LOGNAME': 'cltbld', 16:02:34 INFO - 'MAIL': '/var/mail/cltbld', 16:02:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:34 INFO - 'MOZ_NO_REMOTE': '1', 16:02:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:34 INFO - 'NO_EM_RESTART': '1', 16:02:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:34 INFO - 'PWD': '/builds/slave/test', 16:02:34 INFO - 'SHELL': '/bin/bash', 16:02:34 INFO - 'SHLVL': '1', 16:02:34 INFO - 'TERM': 'linux', 16:02:34 INFO - 'TMOUT': '86400', 16:02:34 INFO - 'USER': 'cltbld', 16:02:34 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', 16:02:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:34 INFO - '_': '/tools/buildbot/bin/python'} 16:02:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:02:34 INFO - Running setup.py (path:/tmp/pip-OZpbOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:02:35 INFO - Running setup.py (path:/tmp/pip-9fubxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:02:35 INFO - Running setup.py (path:/tmp/pip-eNR0Vy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:02:35 INFO - Running setup.py (path:/tmp/pip-dv78VZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:02:35 INFO - Running setup.py (path:/tmp/pip-EkCF7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:02:35 INFO - Running setup.py (path:/tmp/pip-WF3aFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:02:35 INFO - Running setup.py (path:/tmp/pip-JnJ5vH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:02:35 INFO - Running setup.py (path:/tmp/pip-d23Upr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:02:35 INFO - Running setup.py (path:/tmp/pip-ggvKbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:02:35 INFO - Running setup.py (path:/tmp/pip-nPqPDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:02:35 INFO - Running setup.py (path:/tmp/pip-QzxZq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:02:35 INFO - Running setup.py (path:/tmp/pip-_rEpop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:02:35 INFO - Running setup.py (path:/tmp/pip-8EGdRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:02:35 INFO - Running setup.py (path:/tmp/pip-8uJJTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:35 INFO - Running setup.py (path:/tmp/pip-lXoPtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:02:36 INFO - Running setup.py (path:/tmp/pip-GUdxUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:02:36 INFO - Running setup.py (path:/tmp/pip-1KI3Z1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:02:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:02:36 INFO - Running setup.py install for manifestparser 16:02:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:02:36 INFO - Running setup.py install for mozcrash 16:02:36 INFO - Running setup.py install for mozdebug 16:02:36 INFO - Running setup.py install for mozdevice 16:02:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:02:36 INFO - Running setup.py install for mozfile 16:02:36 INFO - Running setup.py install for mozhttpd 16:02:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:02:36 INFO - Running setup.py install for mozinfo 16:02:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:02:36 INFO - Running setup.py install for mozInstall 16:02:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:02:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:02:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:02:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:02:36 INFO - Running setup.py install for mozleak 16:02:37 INFO - Running setup.py install for mozlog 16:02:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:02:37 INFO - Running setup.py install for moznetwork 16:02:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:02:37 INFO - Running setup.py install for mozprocess 16:02:37 INFO - Running setup.py install for mozprofile 16:02:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:02:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:02:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:02:37 INFO - Running setup.py install for mozrunner 16:02:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:02:37 INFO - Running setup.py install for mozscreenshot 16:02:37 INFO - Running setup.py install for moztest 16:02:37 INFO - Running setup.py install for mozversion 16:02:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:02:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:02:37 INFO - Cleaning up... 16:02:37 INFO - Return code: 0 16:02:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02: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:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02: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:02:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:38 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 0x1afd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da20b0>, '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 0x1da2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da3440>, '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': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', '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:02:38 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:02:38 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:02:38 INFO - Using env: {'DISPLAY': ':0', 16:02:38 INFO - 'HOME': '/home/cltbld', 16:02:38 INFO - 'LANG': 'en_US.UTF-8', 16:02:38 INFO - 'LANGUAGE': 'en_US:en', 16:02:38 INFO - 'LOGNAME': 'cltbld', 16:02:38 INFO - 'MAIL': '/var/mail/cltbld', 16:02:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:38 INFO - 'MOZ_NO_REMOTE': '1', 16:02:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:38 INFO - 'NO_EM_RESTART': '1', 16:02:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:38 INFO - 'PWD': '/builds/slave/test', 16:02:38 INFO - 'SHELL': '/bin/bash', 16:02:38 INFO - 'SHLVL': '1', 16:02:38 INFO - 'TERM': 'linux', 16:02:38 INFO - 'TMOUT': '86400', 16:02:38 INFO - 'USER': 'cltbld', 16:02:38 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', 16:02:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:38 INFO - '_': '/tools/buildbot/bin/python'} 16:02:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:02:38 INFO - Running setup.py (path:/tmp/pip-MuH_83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:02:38 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:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:02:38 INFO - Running setup.py (path:/tmp/pip-7sdITf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:02:38 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:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:02:38 INFO - Running setup.py (path:/tmp/pip-_1vNwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:02:38 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:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:02:38 INFO - Running setup.py (path:/tmp/pip-kb8jac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:02:38 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:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:02:38 INFO - Running setup.py (path:/tmp/pip-f7d5Zb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:02:38 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:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:02:38 INFO - Running setup.py (path:/tmp/pip-JXiqR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:02:38 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:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:02:38 INFO - Running setup.py (path:/tmp/pip-Dg4rFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:02:38 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:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:02:38 INFO - Running setup.py (path:/tmp/pip-RhvWPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:02:38 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:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:02:38 INFO - Running setup.py (path:/tmp/pip-ukusMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:02:39 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:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:02:39 INFO - Running setup.py (path:/tmp/pip-A8DSzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:02:39 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:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:02:39 INFO - Running setup.py (path:/tmp/pip-UwSaDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:02:39 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:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:02:39 INFO - Running setup.py (path:/tmp/pip-FzYxnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:02:39 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:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:02:39 INFO - Running setup.py (path:/tmp/pip-zUt50w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:02:39 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:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:02:39 INFO - Running setup.py (path:/tmp/pip-iT0zRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:02:39 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:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:39 INFO - Running setup.py (path:/tmp/pip-VCXqaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:02:39 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:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:02:39 INFO - Running setup.py (path:/tmp/pip-jkF7TX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:02:39 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:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:02:39 INFO - Running setup.py (path:/tmp/pip-15gdE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:02:39 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:02:39 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:02:39 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:02:39 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:02:39 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:02:39 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:02:39 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:02:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:02:39 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:02:39 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:02:39 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:02:39 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:02:40 INFO - Downloading blessings-1.6.tar.gz 16:02:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:02:40 INFO - Installing collected packages: blessings 16:02:40 INFO - Running setup.py install for blessings 16:02:40 INFO - Successfully installed blessings 16:02:40 INFO - Cleaning up... 16:02:40 INFO - Return code: 0 16:02:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:02:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:02:41 INFO - Reading from file tmpfile_stdout 16:02:41 INFO - Current package versions: 16:02:41 INFO - argparse == 1.2.1 16:02:41 INFO - blessings == 1.6 16:02:41 INFO - blobuploader == 1.2.4 16:02:41 INFO - docopt == 0.6.1 16:02:41 INFO - functools32 == 3.2.3-2 16:02:41 INFO - jsonschema == 2.5.1 16:02:41 INFO - manifestparser == 1.1 16:02:41 INFO - mozInstall == 1.13 16:02:41 INFO - mozcrash == 1.0 16:02:41 INFO - mozdebug == 0.1 16:02:41 INFO - mozdevice == 0.51 16:02:41 INFO - mozfile == 1.2 16:02:41 INFO - mozhttpd == 0.7 16:02:41 INFO - mozinfo == 0.10 16:02:41 INFO - mozleak == 0.1 16:02:41 INFO - mozlog == 3.5 16:02:41 INFO - moznetwork == 0.27 16:02:41 INFO - mozprocess == 0.25 16:02:41 INFO - mozprofile == 0.28 16:02:41 INFO - mozrunner == 6.13 16:02:41 INFO - mozscreenshot == 0.1 16:02:41 INFO - mozsystemmonitor == 0.3 16:02:41 INFO - moztest == 0.8 16:02:41 INFO - mozversion == 1.4 16:02:41 INFO - psutil == 3.1.1 16:02:41 INFO - requests == 1.2.3 16:02:41 INFO - wsgiref == 0.1.2 16:02:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:02:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:41 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 0x1afd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da20b0>, '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 0x1da2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da3440>, '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': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', '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:02:41 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:02:41 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:02:41 INFO - Using env: {'DISPLAY': ':0', 16:02:41 INFO - 'HOME': '/home/cltbld', 16:02:41 INFO - 'LANG': 'en_US.UTF-8', 16:02:41 INFO - 'LANGUAGE': 'en_US:en', 16:02:41 INFO - 'LOGNAME': 'cltbld', 16:02:41 INFO - 'MAIL': '/var/mail/cltbld', 16:02:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:41 INFO - 'MOZ_NO_REMOTE': '1', 16:02:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:41 INFO - 'NO_EM_RESTART': '1', 16:02:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:41 INFO - 'PWD': '/builds/slave/test', 16:02:41 INFO - 'SHELL': '/bin/bash', 16:02:41 INFO - 'SHLVL': '1', 16:02:41 INFO - 'TERM': 'linux', 16:02:41 INFO - 'TMOUT': '86400', 16:02:41 INFO - 'USER': 'cltbld', 16:02:41 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', 16:02:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:41 INFO - '_': '/tools/buildbot/bin/python'} 16:02:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:41 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:02:41 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:02:41 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:02:41 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:02:41 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:02:41 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:02:41 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:02:41 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:02:41 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:02:41 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:02:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:02:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:02:42 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:02:42 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:02:42 INFO - Installing collected packages: simplejson 16:02:42 INFO - Running setup.py install for simplejson 16:02:42 INFO - building 'simplejson._speedups' extension 16:02:42 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:02:43 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:02:43 INFO - Successfully installed simplejson 16:02:43 INFO - Cleaning up... 16:02:43 INFO - Return code: 0 16:02:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:02:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1afd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1da4ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da20b0>, '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 0x1da2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da3440>, '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': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', '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:02:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:02:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:02:43 INFO - Using env: {'DISPLAY': ':0', 16:02:43 INFO - 'HOME': '/home/cltbld', 16:02:43 INFO - 'LANG': 'en_US.UTF-8', 16:02:43 INFO - 'LANGUAGE': 'en_US:en', 16:02:43 INFO - 'LOGNAME': 'cltbld', 16:02:43 INFO - 'MAIL': '/var/mail/cltbld', 16:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:43 INFO - 'MOZ_NO_REMOTE': '1', 16:02:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:43 INFO - 'NO_EM_RESTART': '1', 16:02:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:43 INFO - 'PWD': '/builds/slave/test', 16:02:43 INFO - 'SHELL': '/bin/bash', 16:02:43 INFO - 'SHLVL': '1', 16:02:43 INFO - 'TERM': 'linux', 16:02:43 INFO - 'TMOUT': '86400', 16:02:43 INFO - 'USER': 'cltbld', 16:02:43 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', 16:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:43 INFO - '_': '/tools/buildbot/bin/python'} 16:02:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:02:43 INFO - Cleaning up... 16:02:43 INFO - Return code: 0 16:02:43 INFO - Running post-action listener: _resource_record_post_action 16:02:43 INFO - Running post-action listener: _start_resource_monitoring 16:02:43 INFO - Starting resource monitoring. 16:02:43 INFO - [mozharness: 2017-08-17 23:02:43.956903Z] Finished create-virtualenv step (success) 16:02:43 INFO - [mozharness: 2017-08-17 23:02:43.957996Z] Running install step. 16:02:43 INFO - Running pre-action listener: _resource_record_pre_action 16:02:43 INFO - Running main action method: install 16:02:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:02:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:02:44 INFO - Reading from file tmpfile_stdout 16:02:44 INFO - Detecting whether we're running mozinstall >=1.0... 16:02:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:02:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:02:44 INFO - Reading from file tmpfile_stdout 16:02:44 INFO - Output received: 16:02:44 INFO - Usage: mozinstall [options] installer 16:02:44 INFO - Options: 16:02:44 INFO - -h, --help show this help message and exit 16:02:44 INFO - -d DEST, --destination=DEST 16:02:44 INFO - Directory to install application into. [default: 16:02:44 INFO - "/builds/slave/test"] 16:02:44 INFO - --app=APP Application being installed. [default: firefox] 16:02:44 INFO - mkdir: /builds/slave/test/build/application 16:02:44 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:02:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:03:02 INFO - Reading from file tmpfile_stdout 16:03:02 INFO - Output received: 16:03:02 INFO - /builds/slave/test/build/application/firefox/firefox 16:03:02 INFO - Running post-action listener: _resource_record_post_action 16:03:02 INFO - [mozharness: 2017-08-17 23:03:02.424985Z] Finished install step (success) 16:03:02 INFO - [mozharness: 2017-08-17 23:03:02.425222Z] Running setup-mitmproxy step. 16:03:02 INFO - Running pre-action listener: _resource_record_pre_action 16:03:02 INFO - Running main action method: setup_mitmproxy 16:03:02 INFO - Skipping: mitmproxy is not required 16:03:02 INFO - Running post-action listener: _resource_record_post_action 16:03:02 INFO - [mozharness: 2017-08-17 23:03:02.426356Z] Finished setup-mitmproxy step (success) 16:03:02 INFO - [mozharness: 2017-08-17 23:03:02.426578Z] Running run-tests step. 16:03:02 INFO - Running pre-action listener: _resource_record_pre_action 16:03:02 INFO - Running pre-action listener: _set_gcov_prefix 16:03:02 INFO - Running main action method: run_tests 16:03:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:03:02 INFO - Python 2.7.3 16:03:02 INFO - Return code: 0 16:03:02 INFO - grabbing minidump binary from tooltool 16:03:02 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:03:02 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 0x1da20b0>, '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 0x1da2fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1da3440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:03:02 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:03:02 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:03:02 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:03:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:03:02 INFO - Return code: 0 16:03:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:03:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:03:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:03:02 INFO - ENV: RUST_BACKTRACE is now full 16:03:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:03:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:03:02 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/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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:03:02 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/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-043 --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:03:02 INFO - Using env: {'DISPLAY': ':0', 16:03:02 INFO - 'HOME': '/home/cltbld', 16:03:02 INFO - 'LANG': 'en_US.UTF-8', 16:03:02 INFO - 'LANGUAGE': 'en_US:en', 16:03:02 INFO - 'LOGNAME': 'cltbld', 16:03:02 INFO - 'MAIL': '/var/mail/cltbld', 16:03:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:03:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:03:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:02 INFO - 'MOZ_NO_REMOTE': '1', 16:03:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:03:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:02 INFO - 'NO_EM_RESTART': '1', 16:03:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:02 INFO - 'PWD': '/builds/slave/test', 16:03:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:03:02 INFO - 'RUST_BACKTRACE': 'full', 16:03:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:03:02 INFO - 'SHELL': '/bin/bash', 16:03:02 INFO - 'SHLVL': '1', 16:03:02 INFO - 'STYLO_FORCE_ENABLED': '1', 16:03:02 INFO - 'TERM': 'linux', 16:03:02 INFO - 'TMOUT': '86400', 16:03:02 INFO - 'USER': 'cltbld', 16:03:02 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633', 16:03:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:02 INFO - '_': '/tools/buildbot/bin/python'} 16:03:02 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/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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:03:03 INFO - mozversion application_buildid: 20170817221603 16:03:03 INFO - mozversion application_changeset: 54ad22b649e547a5b20babdecc84f5563a9b7dea 16:03:03 INFO - mozversion application_display_name: Nightly 16:03:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:03:03 INFO - mozversion application_name: Firefox 16:03:03 INFO - mozversion application_remotingname: firefox 16:03:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:03:03 INFO - mozversion application_vendor: Mozilla 16:03:03 INFO - mozversion application_version: 57.0a1 16:03:03 INFO - mozversion platform_buildid: 20170817221603 16:03:03 INFO - mozversion platform_changeset: 54ad22b649e547a5b20babdecc84f5563a9b7dea 16:03:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:03:03 INFO - mozversion platform_version: 57.0a1 16:03:03 INFO - using testdate: 1503010983 16:03:03 INFO - actual date: 1503010983 16:03:03 INFO - starting webserver on 'localhost:59490' 16:03:03 INFO - SUITE-START | Running 2 tests 16:03:03 INFO - TEST-START | dromaeo_css 16:03:03 INFO - Initialising browser for dromaeo_css test... 16:03:03 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59490/getInfo.html -profile /tmp/tmpLS3AAS/profile 16:03:03 INFO - TEST-INFO | started process 3347 (/builds/slave/test/build/application/firefox/firefox http://localhost:59490/getInfo.html) 16:03:10 INFO - TEST-INFO | 3347: exit 0 16:03:11 INFO - Browser initialized. 16:03:11 INFO - Running cycle 1/1 for dromaeo_css test... 16:03:11 INFO - TEST-INFO | started process 3543 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLS3AAS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:03:12 INFO - PID 3543 | 16:03:12 INFO - PID 3543 | (/builds/slave/test/build/application/firefox/firefox:3597): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:03:12 INFO - PID 3543 | 16:03:22 INFO - PID 3543 | RSS: Main: 169725952 16:03:22 INFO - PID 3543 | 16:06:21 INFO - PID 3543 | Cycle 1(1): loaded http://localhost:59490/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59490/tests/dromaeo/cssquery-ext.html) 16:06:23 INFO - PID 3543 | RSS: Main: 174563328 16:06:23 INFO - PID 3543 | 16:09:24 INFO - PID 3543 | Cycle 1(1): loaded http://localhost:59490/tests/dromaeo/cssquery-ext.html (next: http://localhost:59490/tests/dromaeo/cssquery-jquery.html) 16:09:25 INFO - PID 3543 | RSS: Main: 171929600 16:09:25 INFO - PID 3543 | 16:12:27 INFO - PID 3543 | Cycle 1(1): loaded http://localhost:59490/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59490/tests/dromaeo/cssquery-mootools.html) 16:12:27 INFO - PID 3543 | RSS: Main: 173596672 16:12:27 INFO - PID 3543 | 16:15:29 INFO - PID 3543 | Cycle 1(1): loaded http://localhost:59490/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59490/tests/dromaeo/cssquery-prototype.html) 16:15:30 INFO - PID 3543 | RSS: Main: 172081152 16:15:30 INFO - PID 3543 | 16:18:33 INFO - PID 3543 | Cycle 1(1): loaded http://localhost:59490/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59490/tests/dromaeo/cssquery-yui.html) 16:18:34 INFO - PID 3543 | RSS: Main: 176119808 16:18:34 INFO - PID 3543 | 16:21:36 INFO - PID 3543 | Cycle 1(1): loaded http://localhost:59490/tests/dromaeo/cssquery-yui.html (next: http://localhost:59490/tests/dromaeo/cssquery-dojo.html) 16:21:36 INFO - PID 3543 | RSS: Main: 176766976 16:21:36 INFO - PID 3543 | 16:21:36 INFO - PID 3543 | __start_tp_report 16:21:36 INFO - PID 3543 | _x_x_mozilla_page_load 16:21:36 INFO - PID 3543 | _x_x_mozilla_page_load_details 16:21:36 INFO - PID 3543 | |i|pagename|runs| 16:21:36 INFO - PID 3543 | |0;dojo.html;3103.6741142180085;3156.384505021521;3171.191346206717;3191.329820737645;3210.229544909221;6049.243844519435;6090.573659843807;6172.290186628527;6174.259088909332;6259.123722678829;6111.938880611193;6180.1656776335185;6221.0979407985815;6260.5930614510025;6282.3717628237155;5478;5579.721013949306;5658.801941932013;5672.205891175238;5724.7996320128805;5471;5590.133529302964;5613.831585052455;5698.772049118051;5700.144978253254;5607.80372686954;5631.324241091075;5642.351129620105;5651.124075768263;5683.119116536902;5569;5614.29821272341;5658.943410565883;5683.772649094031;5695.601307908448;5458.153986132156;5548.056830338852;5554.888902221974;5570.470805273492;5600.943990560124;6027.065804800263;6079.574429789916;6096.634201947897;6099.69501524922;6124.35694252106;6575.80272591823;6586.332693228526;6643.136392268974;6677.732890684415;6687.3981341679;6448.516361272877;6546.541742078057;6573.1783527059115;6634.270230274666;6777.830554236183;6152.477039451696;6155.507559395238;6176;6181.783637572753;6182.598131121507;6712.463002959751;6771.559848609824;6800.557963732342;6817.522773405814;6850.212225594021;6421;6455.580387274812;6456.709448074848;6457.773977910751;6460.095586617877;6568.1789776277965;6570.145881035435;6573.539852210395;6575.671216439159;6581.506387020992;6492.156019717407;6561.0814485972005;6582.045603387482;6642.6014439133805;6643.534952553371;6611.801645950629;6663.400293973468;6688.665566721645;6699.665016749143;6706.798796036127;5377.838664840061;5429.592780541396;5459.317585301837;5475.890482190334;5477.945220547744;4580.175568397721;4641.651876109306;4651.139539185261;4659.697119687209;4663.556962088596;5722.170285308607;5784.971075144682;5845.357010728816;5859;5859.531237501075;6710.899336509956;6746.190457145174;6779.898301525481;6794.11676482064;6794.252632210453;5751.166080918327;5757.510611597977;5883.529317654577;5885.882282354414;5888.057910734262;5776.566757493205;5880;5880.117982302689;5904;5986.161937328778;6493.285738568754;6508.869822603616;6513.576617519941;6545.541812073205;6553;6648.335166483506;6693.062971184136;6725.730970761115;6739.123913891162;6782.559133656297;6438.90341644885;6478.190226221723;6565.967170164118;6568.80293591193;6594.934050659432;6534.248561415308;6575.901361479486;6631.303713110247;6641.833954151185;6642.966785166043;6481.805545833632;6597.373249541286;6607.9669601651685;6669.799906002828;6685.933140668726;6402.263739669997;6405.551611387158;6460.289368169404;6481.384268494486;6487.026945958144;6431.678416079271;6488.610683359014;6524.9021264682015;6528.902066469102;6531.412172904462;6353.872922541431;6365.490760739037;6445.8388540285;6450.548461607643;6474.028895665688;6377.426031657174;6419.614823110628;6453.419192272718;6471.838204044937;6499.610023398611;6317.178766760292;6360.77737279193;6381.074744162163;6398.456131228898;6399.168108145911 16:21:36 INFO - PID 3543 | |1;ext.html;822.7490615362312;875.4222213339195;896.7444278380659;900.4282280751721;1173.3722458484701;31190.376192476164;31849.36301273973;42238.78880605601;42962.57037429625;43014.35478467825;43041.139177216435;43423;43469.34795978063;43529.7823510882;43590;7308.853822923538;7614.162442131361;7884.724034658788;7888.1323054463965;7915.327197188246;7718.961405192967;7856.72501462449;7858.842823143534;7864.606769661523;7865.056193256789;7830.921690783076;7893.5658538780635;7904.960475197616;7906.920930790705;7908.051033875943;7810.531368117931;7840.098388685317;7848;7858.921410785904;7861.921380786176;7692.4615276930635;7713;7732.845343093163;7750.689972401097;7775.494592851446;38492;38572;38576.42135367972;38615.42076868849;38749;3533.5406397168204;3571.285742851414;3581.7850928944345;3585.318789430026;3586.1393265616325;31969.360612787616;31977.840110799298;32016;32021.679783202337;32092.358152837045;5016.197408414636;5047.772850221749;5054.570361519275;5092.516210959953;5098.898022039575;189187;189236;189386;189670.99999999863;190153;48774.26838597424;48934.51065489371;48959.265611015864;48965.510344896094;48971;51275.7436212824;51297.97404051898;51401.48598514042;51407.22889156591;51449;13544.322783860767;15339.156346401054;15525.844741552666;15573.922130389275;15585.610359741097;11170.497327620276;12372.257664540157;12555.183913045623;12575.748485030432;12579.433925473193;14192.503262385768;14206.928965355313;14230.501932432315;14240.430382784572;14318.498852540319;5770.451807078321;5824;5833.7083145842535;5841.211436456109;5873.119032145212;982.6708052802248;992.9205663546899;998.6255154317131;1003.9949800250952;1016.4612755239734;22147.667784983238;22266.666000010013;22496.21263255811;22502.21242256513;22554.759488228305;1094.2559059839386;1096.3969816600838;1102.6967583914488;1103.9779204416027;1115.0912006714502;11953.043756499459;12072;16009.839901601068;16088.678226435406;16559.751603725952;236351;238038;238066;238228;238478;56932.715336423054;57304.14043789347;57311;57376.71311643415;57501;1425.237497938598;1453.7644245349975;1473.8231412230386;1491.6643755155003;1502.3314624991992;27539.449211016068;27721.168364949084;27765.722342776313;27864.860675697302;28503.29218724218;53205.4679453216;53642;53898.19152712634;53941;53964;204737;207213;207222;207721;209068;32911;32958.67041329556;33047.33905321928;33326.500102497994;37489;45503.31745023761;45542.54457455383;45690.77154614206;46061.53938460573;46484;33287.334253314315;33414.498782517796;33634;33714.49428258529;33851.66148338485;34236.657633424344;34474.31051378908;34971.30057398888;35449.645503544634;35514.11214719652;37547.43678844765;37610.05974850651;37672.43491347577;37787.05532361713;37855.62144378521;32896.67103329032;33571;33971.66028339685;34108.488372673935;34258.48612270868;23827.523449531258;24327.27018189457;24372.390690232885;24905.87547062253;25207.36981575402 16:21:36 INFO - PID 3543 | |2;jquery.html;568.6758547627851;574.6323749476246;582.8484594005557;584.5966283264546;603.7584966013594;22769.31692049239;22929.77070229297;23494.5301093978;23630;23671;26227.86886065567;26782.5982610261;26822.731772682266;26861.194164175056;26909.0581829636;6602.768903088393;6763.256041835419;6768.69540870662;6782.694778734969;6785.457163426939;5869.677167755772;6406;6422.389872962084;6451.774187903425;6617.503687223453;6403.615783053008;6421.614703117804;6422.903656445157;6424;6426.228852537702;6404.551681382305;6408.519361047916;6409.070684750685;6414.294427612959;6416.165898433221;5928.881422371572;5932.525397968152;5939.524838012949;5945.108233764927;5952.4642782149385;28842;29569.26076848096;29735.553966690517;29907;30477.238069048228;1932.9323473678494;1965.2139144342234;1966.7443232379842;1970.7240986261934;1971.6648169811167;18982.71525927112;19207.231710731554;20067.1973121075;20197.192112315635;20266.79733202664;2373.8694371809543;2395.2933884504114;2460.2127319257843;2467.8519288842726;2484.3168128764555;140279;146431;146682.26658866636;146706;146897;14658.071469200193;14999.350850386028;15045.097294162166;15167.772483412758;15218.064954667689;23707.762922370555;23732.525349492913;23793.286201413986;23840.88079559626;23882.52234955291;9560.235181185488;9633.807323853483;9715.757106072264;9891.455969921579;9902.702918912444;8859.424137431046;8904.643814247487;8918.331125165638;8935.195832375117;8997.100289970951;9186.081391860895;9218.124278193562;9321.394109382869;9336.15974562279;9416.811663766686;2681.2760554650135;2722.48272828162;2771.5011297966157;2782.3461486550646;2802;975.8097171051719;982.7641366072091;989.9059589339001;1008.6721815410021;1014.9137323327452;19797.40607781769;20103.798962010485;20127.798722012594;20239;20777.584448311252;1034.5809946971488;1045.4929359260752;1046.4610725476387;1051.8632577764843;1062.6652604429735;11065.723356916142;11188.384638844778;11210.943945280222;11273.605423810128;11285.887141128484;508150;526599;529777;537068;542271;112914;114324.42837785924;114685;116652;116974;1087.5541028178409;1130.6262890587975;1174.236746115015;1208.5528354508888;1243.8320826688282;21166.153353866288;21407.67888481643;21464.78535214628;21518.139274429475;21617.351479455643;55037.89924201574;56062;56171.719141405665;56879.43120568907;57228.42771572398;70176.29823701698;70371.64814175897;70481.6475917617;70745.64627176832;70863;14566.781498277322;14895.478658247343;14974.326155322819;15013.924930375715;15039.248037598296;23177.536449271258;23284.65073023872;23489.530209395376;23559.528809423373;23684.644730329404;18000.279988800827;18231.361902333887;18274.269029239214;18399.08004599738;18513.259469621604;15797.605059873595;15889;16436.768980293324;16709.2480838365;17111;18000.639987200444;18777.530561736065;18910.243590256785;19252.614947701248;19299.421017369503;14253.64365890861;14907.701845962803;14934.178620175995;14950.25248737537;14987.100773953598;18315.908420458345;18426.815731843046;18568.164432600835;18578.25686972506;18868.811311887253 16:21:36 INFO - PID 3543 | |3;mootools.html;1367.2548461088707;1404.059280282211;1415.6460884778805;1417.851125631808;1434.7130573885227;13859.445622175113;14386.640333991656;14438.5668429947;14469.710605787877;14473.927630361859;13980.930095349535;14943.925280373584;14957.92521037396;14964.401423943056;14975.775363369557;2625.855577943212;2675.558532842079;2676.879540420676;2678.866056697167;2686.4895669822768;2579.36805482657;2599.636050952868;2650.4301575161335;2654.6283520307175;2658.3885706287565;2610.7519785620334;2619.5808670612705;2630.671166104237;2669.7997650176217;2674.665666791651;2544.669193004907;2555.092942005589;2559.2322303308933;2566.2814411964678;2567.8844451999703;2534.3790771260974;2538.2765911715073;2548.6559314492492;2548.9490210195877;2553.093651753638;13769.380377883019;14052.718945621133;14061.437542498288;14062;14071.226082565454;7379.52033117851;7623.847523049563;7657.387409007321;7657.885131723028;7662.387009039264;4878.878028049292;5024.62315326352;5060.493950604947;5135.204043373283;5148.304978827848;1911.6081203353278;1921.8942958137297;1922.9230830766753;1959.6668566343756;1968.7342208801772;22747.88626056859;22954.77045229543;23050.92541671241;23082.42293942648;23106.30681079587;6165.506759459322;6397.136386587844;6430.839229019312;6531.902021469682;6567.277599464055;15880.20598970047;15999.440019599258;16012.439564615182;16018.839811601734;16032.519024429286;7260.4554658399775;7334.4499162563025;7405.740799072114;7421.332080112816;7423.9257607424315;5679.403662615366;5961.165436838846;6221.4089661482085;6235.937640623536;6820.181578210647;4824.975875120672;4958.388523729802;5036.345275114285;5048.671836330626;5056.191009438472;1353.8984576156827;1370.4518192722792;1379.9103058301177;1390.7635701930697;1400.0689541455022;914.1863741270275;939.793245485992;941.5621735892706;950.4344914775697;953.323140570189;7402.518836275625;7462.589557574385;7469.402447804163;7470.738524151629;7508.849823003509;646.4957333280049;664.9667516624246;669.6183175589867;671.1141293492641;680.8502129531495;5678.375378708339;5727.799527016535;5768.221290125864;5805.651660900359;5937.970310148422;19548.70676939847;20315;20419.28532501385;20438.59122817535;20439.8978005112;19276.132574034204;19699.11353989074;19778.70331945022;19812.60374792496;19995.40013799559;10916;11060.612878549213;11083.334999900031;11083.556657733601;11140.49867755953;7939.603019848869;8010.479318844256;8549.957250213709;8601.698940537051;8802.38383313162;17304.567385815455;17356.739648905022;17445.04052277137;17448.73826892623;18261.360852370104;17728.734068989233;17815.198316075548;18216.271349145885;18299.54251143732;18463.63072738511;10726.356418614596;10972;10990.670279891616;10997.175211859303;11016.504257308601;13072.215667060007;13301.600951971457;13408.061435699407;13415.731685366432;13452.730945380841;10158.187345012233;10255.538500767334;10302.124319432933;10312.123469504875;10465.633702820365;10632.096271816983;10682.145428365557;10698.62554810578;10843.67468975932;11122.499487522919;9274.304427167854;9280.802491864393;9381;9385.343025988115;9400.15398614128;7126.893096603659;7198.856022879618;7248.275172482709;7360.1535823381;7525.3227209551405;7164.319389657974;7203.819904502429;7291;7292.380147687506;7333.449991250571 16:21:36 INFO - PID 3543 | |4;prototype.html;499.9325091112699;502.1137691973666;523.3066187301824;528.4134610582255;534.8152795829038;6165.414285642868;6165.599236049654;6193.411625895544;6204.937950620492;6269.7492100315985;6019.578629495937;6046.946292122483;6076.7569297228165;6102.237220347457;6208.534359923001;4873.683210591318;4950.529699678525;4982.825601103962;5065.772040258197;5078.720670363128;4958.181899986498;4964.925526117111;4978.406909788869;5015.673981191229;5023.246513023039;4909.705417674933;4921.753912304388;4927.113119638464;4947.727874966876;5000.32495613093;4842.782074806624;4902.558769710708;4936.33359496467;4956.355673762388;4972.900541989158;4458.08609235106;4460.8773858299;4464.486584042812;4502.41468609082;4507.549245075499;5917.319508256563;5937.198478205431;5967.4186340619335;6014.939850601526;6071.241094863142;5600.355959064719;5698.8290351289525;5721.914171287434;5733.3406658234;5735.225744524478;6481.967590162066;6515.739370425177;6554.24626167992;6556.639384833832;6571.44142747867;4854.446577095331;4856.587190088847;4893.290472881448;4896.363472748555;4911.582515486188;6529.020646903003;6531.530405658688;6543.378379053982;6781.389674929231;6896.27589103147;5002.299678045077;5020.146575082244;5028.547430731179;5028.899422011613;5037.093323201786;5067.138586440314;5088.465711100355;5095.821646242363;5114.55300105394;5114.948850511447;5646;5669.5005844330935;5677.545796336283;5691.4877661010705;5691.658500489901;6544.803655890331;6596.439302659328;6607.834804129839;6624.304448032984;6627.668616569153;1772.6543324051686;1772.97340539892;1784;1797.5146710388121;1797.7662903822682;1165.8367828504017;1201.4233168079197;1218.4638758946212;1222.4926655437894;1232.8952039076778;5697.373288938214;5728.427157284238;5757.65454072757;5795.203486919068;5834.562407819346;6587.275399706028;6648.501362397839;6655.800325990228;6666.96666516674;6741.865032759692;5661.207430959669;5671.808920126737;5733.88532229353;5790.507806836382;5804.970975145097;3552.3605750964557;3573.446115852047;3602.5857026442295;3612.3317186320614;3613.0967258185456;6475.740970361133;6486.54594178403;6547.377999090078;6573.488097737451;6586.5389422739945;6803.7618683345845;6829.590224586541;6841.93158068413;6853.965730171317;6948.791536253921;6448.9032664511005;6481.611103333815;6545.509086818508;6548.345165483413;6608.4052435281055;6689.565178263398;6741.898871516833;6753.696083676345;6765.932340676531;6767.864642707217;6103.206583144163;6505.381988711065;6600.735970561123;6615.437687796399;6623.688155922039;6410.647414392253;6450.645214513059;6450.838729031811;6504.6747662615735;6504.739810407531;5016.573591244639;5021.297018417497;5028.4971502850885;5031.396232452129;5055.797768089235;6465.547411681137;6502.349765023649;6588.571742836701;6592.208934927839;6650.866982660416;6400.455961243347;6425.935740642533;6457.580257283262;6530.510211734234;6544.738210471528;6495.642739649175;6506;6508.056331831764;6555.115059467102;6590.901136483052;6382.319735435373;6399.232092148785;6419.935800641933;6490.837729056811;6502.187226596676;6533.575317604531;6542.313057129123;6616.8345791355605;6646.368594983469;6692.330766923269;6348.714307856065;6381.010943303795;6428.614283143026;6450.8064758057335;6470.417662410406 16:21:36 INFO - PID 3543 | |5;yui.html;885.7209978856664;904.6607522179182;911.1162172692489;917.9357444978854;927.2767241551592;17709.64580708385;18859.62280754386;19626.509337266558;19707.802921970775;19836.60326793463;16087.678246435064;20672;21003.894980525114;21155.682664760003;21260.8936955315;1044.2064031336201;1066.845307430426;1067.7864427114569;1071.4160782373585;1072.1369297715337;1048.5910494906993;1058.740608550906;1059.4120263253892;1059.5814653211976;1060.188955449082;1056.4242487844106;1093.6937657455885;1098.1434481104711;1105.701460605636;1123.3933675815051;1090.0516550600948;1090.1714696830427;1090.3566895531674;1090.8363745438169;1093.316677076827;794.793353728029;801.286854699318;801.7835184500152;801.8476489467041;801.875709265059;19688.212471501123;20180.29368972079;20213.19147234109;20234;20245.49386265355;21450.249241276637;21664.025118869533;21684.674729879065;22165.778342216534;22200.333989980325;16265.756013659804;16382;16591.91704041472;16593.6681266374;16733.16334183286;3634.309481198564;3652;3653.1780349421433;3654.543182102237;3655.5065066216252;128284;128534;129160;133820;134612;16637.417690381022;16757.41349052777;17041.14794260282;17141.400050998156;17214.827851721573;29852;29924.401511969638;30204.24489387783;30848.383032339676;30868.845655771576;7874.330681891989;8171.877421838677;8497.40518163735;8553.828923421497;8846.380753347205;4376.234159022159;4484.977575112103;4491.797869095899;4499.730016199039;4506.774661266989;5056.974715126401;5131.923021154686;5135.537801597874;5150.9227361588855;5157.277981082651;959.472290240365;976.8241716490958;993.5330394714473;995.2187532786728;996.2926322311096;675.8209074595236;685.2085840853822;703.4337358426413;713.6146480900255;716.2837162837163;11938.286399041412;12185.207961482476;12193.292938988548;12352.938235308946;13167.473301068041;668.2582333609787;669.8760729265101;681.2268075734078;684.8356394465392;697.8988046733195;5937.346891841894;5951.721487780632;6011.729472173763;6035.969820150871;6049.576529642971;107778.46110769396;108809;111318.44340778244;112636.43681781703;113157;94444;96869;97405.02594974102;97453;102436;84055.15944840596;85786.57106714552;87461.56269218613;87473.1252687465;87513.12486875177;10179.7455063624;10613.893861061599;10618.628348007782;10761.139108871115;10991.340519568852;37701;37957.24085518329;38157.80921095377;39136.608633913296;40005.1998960025;48551.75724121357;49365.50634493609;49926;50359.496405036945;50990.490095098576;15044.924775376054;15302.770458443354;15317.846821531642;15432.84567154314;15514.689706206038;17626.91186544059;19197;20158.79841201569;20164.79835201688;20358;18630.44108676742;18735.156917939;18747.15637796275;18815.717764233275;18957.90521047386;17182.656346872744;17191.65616687684;17216.225269862636;17372.044537550555;17625.64748704993;18334.72497912559;18460;18491.907540462213;18493.53766155857;18779.71830422572;11590.826137607773;11795.823062653895;11954.521819127485;12011.339376334035;12069.094817888517;13690.589282321545;13950.720985580028;13955.790663140264;13970.860291396955;14113 16:21:36 INFO - PID 3543 | __end_tp_report 16:21:36 INFO - PID 3543 | __start_cc_report 16:21:36 INFO - PID 3543 | _x_x_mozilla_cycle_collect,376 16:21:36 INFO - PID 3543 | __end_cc_report 16:21:36 INFO - PID 3543 | __startTimestamp1503012096620__endTimestamp 16:21:36 INFO - PID 3543 | 16:21:36 INFO - PID 3543 | ------- Summary: start ------- 16:21:36 INFO - PID 3543 | Number of tests: 6 16:21:36 INFO - PID 3543 | 16:21:36 INFO - PID 3543 | [#0] dojo.html Cycles:165 Average:6112.23 Median:6399.17 stddev:716.78 (11.2%) stddev-sans-first:679.01 16:21:36 INFO - PID 3543 | Values: 3103.7 3156.4 3171.2 3191.3 3210.2 6049.2 6090.6 6172.3 6174.3 6259.1 6111.9 6180.2 6221.1 6260.6 6282.4 5478.0 5579.7 5658.8 5672.2 5724.8 5471.0 5590.1 5613.8 5698.8 5700.1 5607.8 5631.3 5642.4 5651.1 5683.1 5569.0 5614.3 5658.9 5683.8 5695.6 5458.2 5548.1 5554.9 5570.5 5600.9 6027.1 6079.6 6096.6 6099.7 6124.4 6575.8 6586.3 6643.1 6677.7 6687.4 6448.5 6546.5 6573.2 6634.3 6777.8 6152.5 6155.5 6176.0 6181.8 6182.6 6712.5 6771.6 6800.6 6817.5 6850.2 6421.0 6455.6 6456.7 6457.8 6460.1 6568.2 6570.1 6573.5 6575.7 6581.5 6492.2 6561.1 6582.0 6642.6 6643.5 6611.8 6663.4 6688.7 6699.7 6706.8 5377.8 5429.6 5459.3 5475.9 5477.9 4580.2 4641.7 4651.1 4659.7 4663.6 5722.2 5785.0 5845.4 5859.0 5859.5 6710.9 6746.2 6779.9 6794.1 6794.3 5751.2 5757.5 5883.5 5885.9 5888.1 5776.6 5880.0 5880.1 5904.0 5986.2 6493.3 6508.9 6513.6 6545.5 6553.0 6648.3 6693.1 6725.7 6739.1 6782.6 6438.9 6478.2 6566.0 6568.8 6594.9 6534.2 6575.9 6631.3 6641.8 6643.0 6481.8 6597.4 6608.0 6669.8 6685.9 6402.3 6405.6 6460.3 6481.4 6487.0 6431.7 6488.6 6524.9 6528.9 6531.4 6353.9 6365.5 6445.8 6450.5 6474.0 6377.4 6419.6 6453.4 6471.8 6499.6 6317.2 6360.8 6381.1 6398.5 6399.2 16:21:36 INFO - PID 3543 | 16:21:36 INFO - PID 3543 | [#1] ext.html Cycles:180 Average:39078.52 Median:3576.54 stddev:55118.09 (1541.1%) stddev-sans-first:55197.86 16:21:36 INFO - PID 3543 | Values: 822.7 875.4 896.7 900.4 1173.4 31190.4 31849.4 42238.8 42962.6 43014.4 43041.1 43423.0 43469.3 43529.8 43590.0 7308.9 7614.2 7884.7 7888.1 7915.3 7719.0 7856.7 7858.8 7864.6 7865.1 7830.9 7893.6 7905.0 7906.9 7908.1 7810.5 7840.1 7848.0 7858.9 7861.9 7692.5 7713.0 7732.8 7750.7 7775.5 38492.0 38572.0 38576.4 38615.4 38749.0 3533.5 3571.3 3581.8 3585.3 3586.1 31969.4 31977.8 32016.0 32021.7 32092.4 5016.2 5047.8 5054.6 5092.5 5098.9 189187.0 189236.0 189386.0 189671.0 190153.0 48774.3 48934.5 48959.3 48965.5 48971.0 51275.7 51298.0 51401.5 51407.2 51449.0 13544.3 15339.2 15525.8 15573.9 15585.6 11170.5 12372.3 12555.2 12575.7 12579.4 14192.5 14206.9 14230.5 14240.4 14318.5 5770.5 5824.0 5833.7 5841.2 5873.1 982.7 992.9 998.6 1004.0 1016.5 22147.7 22266.7 22496.2 22502.2 22554.8 1094.3 1096.4 1102.7 1104.0 1115.1 11953.0 12072.0 16009.8 16088.7 16559.8 236351.0 238038.0 238066.0 238228.0 238478.0 56932.7 57304.1 57311.0 57376.7 57501.0 1425.2 1453.8 1473.8 1491.7 1502.3 27539.4 27721.2 27765.7 27864.9 28503.3 53205.5 53642.0 53898.2 53941.0 53964.0 204737.0 207213.0 207222.0 207721.0 209068.0 32911.0 32958.7 33047.3 33326.5 37489.0 45503.3 45542.5 45690.8 46061.5 46484.0 33287.3 33414.5 33634.0 33714.5 33851.7 34236.7 34474.3 34971.3 35449.6 35514.1 37547.4 37610.1 37672.4 37787.1 37855.6 32896.7 33571.0 33971.7 34108.5 34258.5 23827.5 24327.3 24372.4 24905.9 25207.4 16:21:36 INFO - PID 3543 | 16:21:36 INFO - PID 3543 | [#2] jquery.html Cycles:180 Average:36199.91 Median:23677.82 stddev:88784.09 (375.0%) stddev-sans-first:88992.85 16:21:36 INFO - PID 3543 | Values: 568.7 574.6 582.8 584.6 603.8 22769.3 22929.8 23494.5 23630.0 23671.0 26227.9 26782.6 26822.7 26861.2 26909.1 6602.8 6763.3 6768.7 6782.7 6785.5 5869.7 6406.0 6422.4 6451.8 6617.5 6403.6 6421.6 6422.9 6424.0 6426.2 6404.6 6408.5 6409.1 6414.3 6416.2 5928.9 5932.5 5939.5 5945.1 5952.5 28842.0 29569.3 29735.6 29907.0 30477.2 1932.9 1965.2 1966.7 1970.7 1971.7 18982.7 19207.2 20067.2 20197.2 20266.8 2373.9 2395.3 2460.2 2467.9 2484.3 140279.0 146431.0 146682.3 146706.0 146897.0 14658.1 14999.4 15045.1 15167.8 15218.1 23707.8 23732.5 23793.3 23840.9 23882.5 9560.2 9633.8 9715.8 9891.5 9902.7 8859.4 8904.6 8918.3 8935.2 8997.1 9186.1 9218.1 9321.4 9336.2 9416.8 2681.3 2722.5 2771.5 2782.3 2802.0 975.8 982.8 989.9 1008.7 1014.9 19797.4 20103.8 20127.8 20239.0 20777.6 1034.6 1045.5 1046.5 1051.9 1062.7 11065.7 11188.4 11210.9 11273.6 11285.9 508150.0 526599.0 529777.0 537068.0 542271.0 112914.0 114324.4 114685.0 116652.0 116974.0 1087.6 1130.6 1174.2 1208.6 1243.8 21166.2 21407.7 21464.8 21518.1 21617.4 55037.9 56062.0 56171.7 56879.4 57228.4 70176.3 70371.6 70481.6 70745.6 70863.0 14566.8 14895.5 14974.3 15013.9 15039.2 23177.5 23284.7 23489.5 23559.5 23684.6 18000.3 18231.4 18274.3 18399.1 18513.3 15797.6 15889.0 16436.8 16709.2 17111.0 18000.6 18777.5 18910.2 19252.6 19299.4 14253.6 14907.7 14934.2 14950.3 14987.1 18315.9 18426.8 18568.2 18578.3 18868.8 16:21:36 INFO - PID 3543 | 16:21:36 INFO - PID 3543 | [#3] mootools.html Cycles:180 Average:8794.44 Median:2554.09 stddev:6104.07 (239.0%) stddev-sans-first:6095.68 16:21:36 INFO - PID 3543 | Values: 1367.3 1404.1 1415.6 1417.9 1434.7 13859.4 14386.6 14438.6 14469.7 14473.9 13980.9 14943.9 14957.9 14964.4 14975.8 2625.9 2675.6 2676.9 2678.9 2686.5 2579.4 2599.6 2650.4 2654.6 2658.4 2610.8 2619.6 2630.7 2669.8 2674.7 2544.7 2555.1 2559.2 2566.3 2567.9 2534.4 2538.3 2548.7 2548.9 2553.1 13769.4 14052.7 14061.4 14062.0 14071.2 7379.5 7623.8 7657.4 7657.9 7662.4 4878.9 5024.6 5060.5 5135.2 5148.3 1911.6 1921.9 1922.9 1959.7 1968.7 22747.9 22954.8 23050.9 23082.4 23106.3 6165.5 6397.1 6430.8 6531.9 6567.3 15880.2 15999.4 16012.4 16018.8 16032.5 7260.5 7334.4 7405.7 7421.3 7423.9 5679.4 5961.2 6221.4 6235.9 6820.2 4825.0 4958.4 5036.3 5048.7 5056.2 1353.9 1370.5 1379.9 1390.8 1400.1 914.2 939.8 941.6 950.4 953.3 7402.5 7462.6 7469.4 7470.7 7508.8 646.5 665.0 669.6 671.1 680.9 5678.4 5727.8 5768.2 5805.7 5938.0 19548.7 20315.0 20419.3 20438.6 20439.9 19276.1 19699.1 19778.7 19812.6 19995.4 10916.0 11060.6 11083.3 11083.6 11140.5 7939.6 8010.5 8550.0 8601.7 8802.4 17304.6 17356.7 17445.0 17448.7 18261.4 17728.7 17815.2 18216.3 18299.5 18463.6 10726.4 10972.0 10990.7 10997.2 11016.5 13072.2 13301.6 13408.1 13415.7 13452.7 10158.2 10255.5 10302.1 10312.1 10465.6 10632.1 10682.1 10698.6 10843.7 11122.5 9274.3 9280.8 9381.0 9385.3 9400.2 7126.9 7198.9 7248.3 7360.2 7525.3 7164.3 7203.8 7291.0 7292.4 7333.4 16:21:36 INFO - PID 3543 | 16:21:36 INFO - PID 3543 | [#4] prototype.html Cycles:180 Average:5491.28 Median:6059.09 stddev:1529.37 (25.2%) stddev-sans-first:1487.07 16:21:36 INFO - PID 3543 | Values: 499.9 502.1 523.3 528.4 534.8 6165.4 6165.6 6193.4 6204.9 6269.7 6019.6 6046.9 6076.8 6102.2 6208.5 4873.7 4950.5 4982.8 5065.8 5078.7 4958.2 4964.9 4978.4 5015.7 5023.2 4909.7 4921.8 4927.1 4947.7 5000.3 4842.8 4902.6 4936.3 4956.4 4972.9 4458.1 4460.9 4464.5 4502.4 4507.5 5917.3 5937.2 5967.4 6014.9 6071.2 5600.4 5698.8 5721.9 5733.3 5735.2 6482.0 6515.7 6554.2 6556.6 6571.4 4854.4 4856.6 4893.3 4896.4 4911.6 6529.0 6531.5 6543.4 6781.4 6896.3 5002.3 5020.1 5028.5 5028.9 5037.1 5067.1 5088.5 5095.8 5114.6 5114.9 5646.0 5669.5 5677.5 5691.5 5691.7 6544.8 6596.4 6607.8 6624.3 6627.7 1772.7 1773.0 1784.0 1797.5 1797.8 1165.8 1201.4 1218.5 1222.5 1232.9 5697.4 5728.4 5757.7 5795.2 5834.6 6587.3 6648.5 6655.8 6667.0 6741.9 5661.2 5671.8 5733.9 5790.5 5805.0 3552.4 3573.4 3602.6 3612.3 3613.1 6475.7 6486.5 6547.4 6573.5 6586.5 6803.8 6829.6 6841.9 6854.0 6948.8 6448.9 6481.6 6545.5 6548.3 6608.4 6689.6 6741.9 6753.7 6765.9 6767.9 6103.2 6505.4 6600.7 6615.4 6623.7 6410.6 6450.6 6450.8 6504.7 6504.7 5016.6 5021.3 5028.5 5031.4 5055.8 6465.5 6502.3 6588.6 6592.2 6650.9 6400.5 6425.9 6457.6 6530.5 6544.7 6495.6 6506.0 6508.1 6555.1 6590.9 6382.3 6399.2 6419.9 6490.8 6502.2 6533.6 6542.3 6616.8 6646.4 6692.3 6348.7 6381.0 6428.6 6450.8 6470.4 16:21:36 INFO - PID 3543 | 16:21:36 INFO - PID 3543 | [#5] yui.html Cycles:180 Average:23151.09 Median:20161.80 stddev:31991.78 (158.7%) stddev-sans-first:32037.84 16:21:36 INFO - PID 3543 | Values: 885.7 904.7 911.1 917.9 927.3 17709.6 18859.6 19626.5 19707.8 19836.6 16087.7 20672.0 21003.9 21155.7 21260.9 1044.2 1066.8 1067.8 1071.4 1072.1 1048.6 1058.7 1059.4 1059.6 1060.2 1056.4 1093.7 1098.1 1105.7 1123.4 1090.1 1090.2 1090.4 1090.8 1093.3 794.8 801.3 801.8 801.8 801.9 19688.2 20180.3 20213.2 20234.0 20245.5 21450.2 21664.0 21684.7 22165.8 22200.3 16265.8 16382.0 16591.9 16593.7 16733.2 3634.3 3652.0 3653.2 3654.5 3655.5 128284.0 128534.0 129160.0 133820.0 134612.0 16637.4 16757.4 17041.1 17141.4 17214.8 29852.0 29924.4 30204.2 30848.4 30868.8 7874.3 8171.9 8497.4 8553.8 8846.4 4376.2 4485.0 4491.8 4499.7 4506.8 5057.0 5131.9 5135.5 5150.9 5157.3 959.5 976.8 993.5 995.2 996.3 675.8 685.2 703.4 713.6 716.3 11938.3 12185.2 12193.3 12352.9 13167.5 668.3 669.9 681.2 684.8 697.9 5937.3 5951.7 6011.7 6036.0 6049.6 107778.5 108809.0 111318.4 112636.4 113157.0 94444.0 96869.0 97405.0 97453.0 102436.0 84055.2 85786.6 87461.6 87473.1 87513.1 10179.7 10613.9 10618.6 10761.1 10991.3 37701.0 37957.2 38157.8 39136.6 40005.2 48551.8 49365.5 49926.0 50359.5 50990.5 15044.9 15302.8 15317.8 15432.8 15514.7 17626.9 19197.0 20158.8 20164.8 20358.0 18630.4 18735.2 18747.2 18815.7 18957.9 17182.7 17191.7 17216.2 17372.0 17625.6 18334.7 18460.0 18491.9 18493.5 18779.7 11590.8 11795.8 11954.5 12011.3 12069.1 13690.6 13950.7 13955.8 13970.9 14113.0 16:21:36 INFO - PID 3543 | -------- Summary: end -------- 16:21:36 INFO - PID 3543 | 16:21:37 INFO - TEST-INFO | 3543: exit 0 16:21:37 INFO - TEST-OK | dromaeo_css | took 1114237ms 16:21:37 INFO - TEST-START | kraken 16:21:37 INFO - Initialising browser for kraken test... 16:21:37 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59490/getInfo.html -profile /tmp/tmpRQd291/profile 16:21:37 INFO - TEST-INFO | started process 3926 (/builds/slave/test/build/application/firefox/firefox http://localhost:59490/getInfo.html) 16:21:43 INFO - TEST-INFO | 3926: exit 0 16:21:43 INFO - Browser initialized. 16:21:43 INFO - Running cycle 1/1 for kraken test... 16:21:43 INFO - TEST-INFO | started process 4119 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRQd291/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:21:44 INFO - PID 4119 | 16:21:44 INFO - PID 4119 | (/builds/slave/test/build/application/firefox/firefox:4173): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:21:44 INFO - PID 4119 | 16:21:55 INFO - PID 4119 | RSS: Main: 166260736 16:21:55 INFO - PID 4119 | 16:21:57 INFO - PID 4119 | Cycle 1(1): loaded http://localhost:59490/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59490/tests/kraken/driver.html?testName=audio-beat-detection) 16:21:58 INFO - PID 4119 | RSS: Main: 173023232 16:21:58 INFO - PID 4119 | 16:22:01 INFO - PID 4119 | Cycle 1(1): loaded http://localhost:59490/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59490/tests/kraken/driver.html?testName=audio-dft) 16:22:02 INFO - PID 4119 | RSS: Main: 176988160 16:22:02 INFO - PID 4119 | 16:22:05 INFO - PID 4119 | Cycle 1(1): loaded http://localhost:59490/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59490/tests/kraken/driver.html?testName=audio-fft) 16:22:05 INFO - PID 4119 | RSS: Main: 171192320 16:22:05 INFO - PID 4119 | 16:22:07 INFO - PID 4119 | Cycle 1(1): loaded http://localhost:59490/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59490/tests/kraken/driver.html?testName=audio-oscillator) 16:22:08 INFO - PID 4119 | RSS: Main: 171479040 16:22:08 INFO - PID 4119 | 16:22:10 INFO - PID 4119 | Cycle 1(1): loaded http://localhost:59490/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59490/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:22:11 INFO - PID 4119 | RSS: Main: 171487232 16:22:11 INFO - PID 4119 | 16:22:14 INFO - PID 4119 | Cycle 1(1): loaded http://localhost:59490/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59490/tests/kraken/driver.html?testName=imaging-darkroom) 16:22:15 INFO - PID 4119 | RSS: Main: 172691456 16:22:15 INFO - PID 4119 | 16:22:18 INFO - PID 4119 | Cycle 1(1): loaded http://localhost:59490/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59490/tests/kraken/driver.html?testName=imaging-desaturate) 16:22:19 INFO - PID 4119 | RSS: Main: 172593152 16:22:19 INFO - PID 4119 | 16:22:22 INFO - PID 4119 | Cycle 1(1): loaded http://localhost:59490/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59490/tests/kraken/driver.html?testName=json-parse-financial) 16:22:23 INFO - PID 4119 | RSS: Main: 172974080 16:22:23 INFO - PID 4119 | 16:22:24 INFO - PID 4119 | Cycle 1(1): loaded http://localhost:59490/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59490/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:22:25 INFO - PID 4119 | RSS: Main: 172965888 16:22:25 INFO - PID 4119 | 16:22:27 INFO - PID 4119 | Cycle 1(1): loaded http://localhost:59490/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59490/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:22:27 INFO - PID 4119 | RSS: Main: 173514752 16:22:27 INFO - PID 4119 | 16:22:30 INFO - PID 4119 | Cycle 1(1): loaded http://localhost:59490/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59490/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:22:30 INFO - PID 4119 | RSS: Main: 173010944 16:22:30 INFO - PID 4119 | 16:22:33 INFO - PID 4119 | Cycle 1(1): loaded http://localhost:59490/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59490/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:22:34 INFO - PID 4119 | RSS: Main: 173805568 16:22:34 INFO - PID 4119 | 16:22:37 INFO - PID 4119 | Cycle 1(1): loaded http://localhost:59490/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59490/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:22:37 INFO - PID 4119 | RSS: Main: 173170688 16:22:37 INFO - PID 4119 | 16:22:39 INFO - PID 4119 | Cycle 1(1): loaded http://localhost:59490/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59490/tests/kraken/driver.html?testName=ai-astar) 16:22:39 INFO - PID 4119 | RSS: Main: 175136768 16:22:39 INFO - PID 4119 | 16:22:39 INFO - PID 4119 | __start_tp_report 16:22:39 INFO - PID 4119 | _x_x_mozilla_page_load 16:22:39 INFO - PID 4119 | _x_x_mozilla_page_load_details 16:22:39 INFO - PID 4119 | |i|pagename|runs| 16:22:39 INFO - PID 4119 | |0;ai-astar;106;108;106;118;108;111;108;99;112;111 16:22:39 INFO - PID 4119 | |1;audio-beat-detection;152;121;147;189;119;151;146;120;179;119 16:22:39 INFO - PID 4119 | |2;audio-dft;162;162;162;162;163;162;165;169;161;169 16:22:39 INFO - PID 4119 | |3;audio-fft;76;76;78;187;77;75;77;74;155;79 16:22:39 INFO - PID 4119 | |4;audio-oscillator;79;79;139;79;85;79;129;79;79;153 16:22:39 INFO - PID 4119 | |5;imaging-gaussian-blur;117;122;114;114;114;114;114;114;115;122 16:22:39 INFO - PID 4119 | |6;imaging-darkroom;150;149;150;156;152;155;150;150;150;152 16:22:39 INFO - PID 4119 | |7;imaging-desaturate;95;96;99;95;94;94;94;94;96;95 16:22:39 INFO - PID 4119 | |8;json-parse-financial;57;55;53;56;58;59;54;53;53;55 16:22:39 INFO - PID 4119 | |9;json-stringify-tinderbox;55;54;60;62;59;61;58;62;56;54 16:22:39 INFO - PID 4119 | |10;stanford-crypto-aes;77;75;76;77;75;75;75;75;77;81 16:22:39 INFO - PID 4119 | |11;stanford-crypto-ccm;134;122;132;130;130;124;128;126;124;170 16:22:39 INFO - PID 4119 | |12;stanford-crypto-pbkdf2;176;200;173;177;171;173;172;187;185;181 16:22:39 INFO - PID 4119 | |13;stanford-crypto-sha256-iterative;75;72;66;71;66;67;68;70;66;70 16:22:39 INFO - PID 4119 | __end_tp_report 16:22:39 INFO - PID 4119 | __start_cc_report 16:22:39 INFO - PID 4119 | _x_x_mozilla_cycle_collect,961 16:22:39 INFO - PID 4119 | __end_cc_report 16:22:39 INFO - PID 4119 | __startTimestamp1503012159954__endTimestamp 16:22:39 INFO - PID 4119 | 16:22:39 INFO - PID 4119 | ------- Summary: start ------- 16:22:39 INFO - PID 4119 | Number of tests: 14 16:22:39 INFO - PID 4119 | 16:22:39 INFO - PID 4119 | [#0] ai-astar Cycles:10 Average:108.70 Median:111.00 stddev:4.92 (4.4%) stddev-sans-first:5.12 16:22:39 INFO - PID 4119 | Values: 106.0 108.0 106.0 118.0 108.0 111.0 108.0 99.0 112.0 111.0 16:22:39 INFO - PID 4119 | 16:22:39 INFO - PID 4119 | [#1] audio-beat-detection Cycles:10 Average:144.30 Median:149.00 stddev:25.23 (16.9%) stddev-sans-first:26.61 16:22:39 INFO - PID 4119 | Values: 152.0 121.0 147.0 189.0 119.0 151.0 146.0 120.0 179.0 119.0 16:22:39 INFO - PID 4119 | 16:22:39 INFO - PID 4119 | [#2] audio-dft Cycles:10 Average:163.70 Median:162.50 stddev:2.98 (1.8%) stddev-sans-first:3.10 16:22:39 INFO - PID 4119 | Values: 162.0 162.0 162.0 162.0 163.0 162.0 165.0 169.0 161.0 169.0 16:22:39 INFO - PID 4119 | 16:22:39 INFO - PID 4119 | [#3] audio-fft Cycles:10 Average:95.40 Median:76.50 stddev:40.58 (53.0%) stddev-sans-first:42.43 16:22:39 INFO - PID 4119 | Values: 76.0 76.0 78.0 187.0 77.0 75.0 77.0 74.0 155.0 79.0 16:22:39 INFO - PID 4119 | 16:22:39 INFO - PID 4119 | [#4] audio-oscillator Cycles:10 Average:98.00 Median:79.00 stddev:29.82 (37.7%) stddev-sans-first:30.82 16:22:39 INFO - PID 4119 | Values: 79.0 79.0 139.0 79.0 85.0 79.0 129.0 79.0 79.0 153.0 16:22:39 INFO - PID 4119 | 16:22:39 INFO - PID 4119 | [#5] imaging-gaussian-blur Cycles:10 Average:116.00 Median:114.50 stddev:3.30 (2.9%) stddev-sans-first:3.48 16:22:39 INFO - PID 4119 | Values: 117.0 122.0 114.0 114.0 114.0 114.0 114.0 114.0 115.0 122.0 16:22:39 INFO - PID 4119 | 16:22:39 INFO - PID 4119 | [#6] imaging-darkroom Cycles:10 Average:151.40 Median:151.00 stddev:2.37 (1.6%) stddev-sans-first:2.46 16:22:39 INFO - PID 4119 | Values: 150.0 149.0 150.0 156.0 152.0 155.0 150.0 150.0 150.0 152.0 16:22:39 INFO - PID 4119 | 16:22:39 INFO - PID 4119 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:1.55 (1.6%) stddev-sans-first:1.64 16:22:39 INFO - PID 4119 | Values: 95.0 96.0 99.0 95.0 94.0 94.0 94.0 94.0 96.0 95.0 16:22:39 INFO - PID 4119 | 16:22:39 INFO - PID 4119 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.50 stddev:2.16 (3.9%) stddev-sans-first:2.20 16:22:39 INFO - PID 4119 | Values: 57.0 55.0 53.0 56.0 58.0 59.0 54.0 53.0 53.0 55.0 16:22:39 INFO - PID 4119 | 16:22:39 INFO - PID 4119 | [#9] json-stringify-tinderbox Cycles:10 Average:58.10 Median:59.50 stddev:3.18 (5.3%) stddev-sans-first:3.17 16:22:39 INFO - PID 4119 | Values: 55.0 54.0 60.0 62.0 59.0 61.0 58.0 62.0 56.0 54.0 16:22:39 INFO - PID 4119 | 16:22:39 INFO - PID 4119 | [#10] stanford-crypto-aes Cycles:10 Average:76.30 Median:76.50 stddev:1.89 (2.5%) stddev-sans-first:1.99 16:22:39 INFO - PID 4119 | Values: 77.0 75.0 76.0 77.0 75.0 75.0 75.0 75.0 77.0 81.0 16:22:39 INFO - PID 4119 | 16:22:39 INFO - PID 4119 | [#11] stanford-crypto-ccm Cycles:10 Average:132.00 Median:130.00 stddev:13.89 (10.7%) stddev-sans-first:14.71 16:22:39 INFO - PID 4119 | Values: 134.0 122.0 132.0 130.0 130.0 124.0 128.0 126.0 124.0 170.0 16:22:39 INFO - PID 4119 | 16:22:39 INFO - PID 4119 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.50 Median:179.00 stddev:9.07 (5.1%) stddev-sans-first:9.53 16:22:39 INFO - PID 4119 | Values: 176.0 200.0 173.0 177.0 171.0 173.0 172.0 187.0 185.0 181.0 16:22:39 INFO - PID 4119 | 16:22:39 INFO - PID 4119 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:70.00 stddev:3.03 (4.3%) stddev-sans-first:2.35 16:22:39 INFO - PID 4119 | Values: 75.0 72.0 66.0 71.0 66.0 67.0 68.0 70.0 66.0 70.0 16:22:39 INFO - PID 4119 | -------- Summary: end -------- 16:22:39 INFO - PID 4119 | 16:22:40 INFO - PID 4119 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:22:40 INFO - TEST-INFO | 4119: exit 0 16:22:40 INFO - TEST-OK | kraken | took 63254ms 16:22:40 INFO - SUITE-END | took 1177s 16:22:41 INFO - Completed test suite (00:19:37) 16:22:41 INFO - javascript benchmark 16:22:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8492.687740537433, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3103.6741142180085, 3156.384505021521, 3171.191346206717, 3191.329820737645, 3210.229544909221, 6049.243844519435, 6090.573659843807, 6172.290186628527, 6174.259088909332, 6259.123722678829, 6111.938880611193, 6180.1656776335185, 6221.0979407985815, 6260.5930614510025, 6282.3717628237155, 5478.0, 5579.721013949306, 5658.801941932013, 5672.205891175238, 5724.7996320128805, 5471.0, 5590.133529302964, 5613.831585052455, 5698.772049118051, 5700.144978253254, 5607.80372686954, 5631.324241091075, 5642.351129620105, 5651.124075768263, 5683.119116536902, 5569.0, 5614.29821272341, 5658.943410565883, 5683.772649094031, 5695.601307908448, 5458.153986132156, 5548.056830338852, 5554.888902221974, 5570.470805273492, 5600.943990560124, 6027.065804800263, 6079.574429789916, 6096.634201947897, 6099.69501524922, 6124.35694252106, 6575.80272591823, 6586.332693228526, 6643.136392268974, 6677.732890684415, 6687.3981341679, 6448.516361272877, 6546.541742078057, 6573.1783527059115, 6634.270230274666, 6777.830554236183, 6152.477039451696, 6155.507559395238, 6176.0, 6181.783637572753, 6182.598131121507, 6712.463002959751, 6771.559848609824, 6800.557963732342, 6817.522773405814, 6850.212225594021, 6421.0, 6455.580387274812, 6456.709448074848, 6457.773977910751, 6460.095586617877, 6568.1789776277965, 6570.145881035435, 6573.539852210395, 6575.671216439159, 6581.506387020992, 6492.156019717407, 6561.0814485972005, 6582.045603387482, 6642.6014439133805, 6643.534952553371, 6611.801645950629, 6663.400293973468, 6688.665566721645, 6699.665016749143, 6706.798796036127, 5377.838664840061, 5429.592780541396, 5459.317585301837, 5475.890482190334, 5477.945220547744, 4580.175568397721, 4641.651876109306, 4651.139539185261, 4659.697119687209, 4663.556962088596, 5722.170285308607, 5784.971075144682, 5845.357010728816, 5859.0, 5859.531237501075, 6710.899336509956, 6746.190457145174, 6779.898301525481, 6794.11676482064, 6794.252632210453, 5751.166080918327, 5757.510611597977, 5883.529317654577, 5885.882282354414, 5888.057910734262, 5776.566757493205, 5880.0, 5880.117982302689, 5904.0, 5986.161937328778, 6493.285738568754, 6508.869822603616, 6513.576617519941, 6545.541812073205, 6553.0, 6648.335166483506, 6693.062971184136, 6725.730970761115, 6739.123913891162, 6782.559133656297, 6438.90341644885, 6478.190226221723, 6565.967170164118, 6568.80293591193, 6594.934050659432, 6534.248561415308, 6575.901361479486, 6631.303713110247, 6641.833954151185, 6642.966785166043, 6481.805545833632, 6597.373249541286, 6607.9669601651685, 6669.799906002828, 6685.933140668726, 6402.263739669997, 6405.551611387158, 6460.289368169404, 6481.384268494486, 6487.026945958144, 6431.678416079271, 6488.610683359014, 6524.9021264682015, 6528.902066469102, 6531.412172904462, 6353.872922541431, 6365.490760739037, 6445.8388540285, 6450.548461607643, 6474.028895665688, 6377.426031657174, 6419.614823110628, 6453.419192272718, 6471.838204044937, 6499.610023398611, 6317.178766760292, 6360.77737279193, 6381.074744162163, 6398.456131228898, 6399.168108145911], "value": 6059.059098579546, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [822.7490615362312, 875.4222213339195, 896.7444278380659, 900.4282280751721, 1173.3722458484701, 31190.376192476164, 31849.36301273973, 42238.78880605601, 42962.57037429625, 43014.35478467825, 43041.139177216435, 43423.0, 43469.34795978063, 43529.7823510882, 43590.0, 7308.853822923538, 7614.162442131361, 7884.724034658788, 7888.1323054463965, 7915.327197188246, 7718.961405192967, 7856.72501462449, 7858.842823143534, 7864.606769661523, 7865.056193256789, 7830.921690783076, 7893.5658538780635, 7904.960475197616, 7906.920930790705, 7908.051033875943, 7810.531368117931, 7840.098388685317, 7848.0, 7858.921410785904, 7861.921380786176, 7692.4615276930635, 7713.0, 7732.845343093163, 7750.689972401097, 7775.494592851446, 38492.0, 38572.0, 38576.42135367972, 38615.42076868849, 38749.0, 3533.5406397168204, 3571.285742851414, 3581.7850928944345, 3585.318789430026, 3586.1393265616325, 31969.360612787616, 31977.840110799298, 32016.0, 32021.679783202337, 32092.358152837045, 5016.197408414636, 5047.772850221749, 5054.570361519275, 5092.516210959953, 5098.898022039575, 189187.0, 189236.0, 189386.0, 189670.99999999863, 190153.0, 48774.26838597424, 48934.51065489371, 48959.265611015864, 48965.510344896094, 48971.0, 51275.7436212824, 51297.97404051898, 51401.48598514042, 51407.22889156591, 51449.0, 13544.322783860767, 15339.156346401054, 15525.844741552666, 15573.922130389275, 15585.610359741097, 11170.497327620276, 12372.257664540157, 12555.183913045623, 12575.748485030432, 12579.433925473193, 14192.503262385768, 14206.928965355313, 14230.501932432315, 14240.430382784572, 14318.498852540319, 5770.451807078321, 5824.0, 5833.7083145842535, 5841.211436456109, 5873.119032145212, 982.6708052802248, 992.9205663546899, 998.6255154317131, 1003.9949800250952, 1016.4612755239734, 22147.667784983238, 22266.666000010013, 22496.21263255811, 22502.21242256513, 22554.759488228305, 1094.2559059839386, 1096.3969816600838, 1102.6967583914488, 1103.9779204416027, 1115.0912006714502, 11953.043756499459, 12072.0, 16009.839901601068, 16088.678226435406, 16559.751603725952, 236351.0, 238038.0, 238066.0, 238228.0, 238478.0, 56932.715336423054, 57304.14043789347, 57311.0, 57376.71311643415, 57501.0, 1425.237497938598, 1453.7644245349975, 1473.8231412230386, 1491.6643755155003, 1502.3314624991992, 27539.449211016068, 27721.168364949084, 27765.722342776313, 27864.860675697302, 28503.29218724218, 53205.4679453216, 53642.0, 53898.19152712634, 53941.0, 53964.0, 204737.0, 207213.0, 207222.0, 207721.0, 209068.0, 32911.0, 32958.67041329556, 33047.33905321928, 33326.500102497994, 37489.0, 45503.31745023761, 45542.54457455383, 45690.77154614206, 46061.53938460573, 46484.0, 33287.334253314315, 33414.498782517796, 33634.0, 33714.49428258529, 33851.66148338485, 34236.657633424344, 34474.31051378908, 34971.30057398888, 35449.645503544634, 35514.11214719652, 37547.43678844765, 37610.05974850651, 37672.43491347577, 37787.05532361713, 37855.62144378521, 32896.67103329032, 33571.0, 33971.66028339685, 34108.488372673935, 34258.48612270868, 23827.523449531258, 24327.27018189457, 24372.390690232885, 24905.87547062253, 25207.36981575402], "value": 17749.583266430676, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.6758547627851, 574.6323749476246, 582.8484594005557, 584.5966283264546, 603.7584966013594, 22769.31692049239, 22929.77070229297, 23494.5301093978, 23630.0, 23671.0, 26227.86886065567, 26782.5982610261, 26822.731772682266, 26861.194164175056, 26909.0581829636, 6602.768903088393, 6763.256041835419, 6768.69540870662, 6782.694778734969, 6785.457163426939, 5869.677167755772, 6406.0, 6422.389872962084, 6451.774187903425, 6617.503687223453, 6403.615783053008, 6421.614703117804, 6422.903656445157, 6424.0, 6426.228852537702, 6404.551681382305, 6408.519361047916, 6409.070684750685, 6414.294427612959, 6416.165898433221, 5928.881422371572, 5932.525397968152, 5939.524838012949, 5945.108233764927, 5952.4642782149385, 28842.0, 29569.26076848096, 29735.553966690517, 29907.0, 30477.238069048228, 1932.9323473678494, 1965.2139144342234, 1966.7443232379842, 1970.7240986261934, 1971.6648169811167, 18982.71525927112, 19207.231710731554, 20067.1973121075, 20197.192112315635, 20266.79733202664, 2373.8694371809543, 2395.2933884504114, 2460.2127319257843, 2467.8519288842726, 2484.3168128764555, 140279.0, 146431.0, 146682.26658866636, 146706.0, 146897.0, 14658.071469200193, 14999.350850386028, 15045.097294162166, 15167.772483412758, 15218.064954667689, 23707.762922370555, 23732.525349492913, 23793.286201413986, 23840.88079559626, 23882.52234955291, 9560.235181185488, 9633.807323853483, 9715.757106072264, 9891.455969921579, 9902.702918912444, 8859.424137431046, 8904.643814247487, 8918.331125165638, 8935.195832375117, 8997.100289970951, 9186.081391860895, 9218.124278193562, 9321.394109382869, 9336.15974562279, 9416.811663766686, 2681.2760554650135, 2722.48272828162, 2771.5011297966157, 2782.3461486550646, 2802.0, 975.8097171051719, 982.7641366072091, 989.9059589339001, 1008.6721815410021, 1014.9137323327452, 19797.40607781769, 20103.798962010485, 20127.798722012594, 20239.0, 20777.584448311252, 1034.5809946971488, 1045.4929359260752, 1046.4610725476387, 1051.8632577764843, 1062.6652604429735, 11065.723356916142, 11188.384638844778, 11210.943945280222, 11273.605423810128, 11285.887141128484, 508150.0, 526599.0, 529777.0, 537068.0, 542271.0, 112914.0, 114324.42837785924, 114685.0, 116652.0, 116974.0, 1087.5541028178409, 1130.6262890587975, 1174.236746115015, 1208.5528354508888, 1243.8320826688282, 21166.153353866288, 21407.67888481643, 21464.78535214628, 21518.139274429475, 21617.351479455643, 55037.89924201574, 56062.0, 56171.719141405665, 56879.43120568907, 57228.42771572398, 70176.29823701698, 70371.64814175897, 70481.6475917617, 70745.64627176832, 70863.0, 14566.781498277322, 14895.478658247343, 14974.326155322819, 15013.924930375715, 15039.248037598296, 23177.536449271258, 23284.65073023872, 23489.530209395376, 23559.528809423373, 23684.644730329404, 18000.279988800827, 18231.361902333887, 18274.269029239214, 18399.08004599738, 18513.259469621604, 15797.605059873595, 15889.0, 16436.768980293324, 16709.2480838365, 17111.0, 18000.639987200444, 18777.530561736065, 18910.243590256785, 19252.614947701248, 19299.421017369503, 14253.64365890861, 14907.701845962803, 14934.178620175995, 14950.25248737537, 14987.100773953598, 18315.908420458345, 18426.815731843046, 18568.164432600835, 18578.25686972506, 18868.811311887253], "value": 12100.333357411018, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1367.2548461088707, 1404.059280282211, 1415.6460884778805, 1417.851125631808, 1434.7130573885227, 13859.445622175113, 14386.640333991656, 14438.5668429947, 14469.710605787877, 14473.927630361859, 13980.930095349535, 14943.925280373584, 14957.92521037396, 14964.401423943056, 14975.775363369557, 2625.855577943212, 2675.558532842079, 2676.879540420676, 2678.866056697167, 2686.4895669822768, 2579.36805482657, 2599.636050952868, 2650.4301575161335, 2654.6283520307175, 2658.3885706287565, 2610.7519785620334, 2619.5808670612705, 2630.671166104237, 2669.7997650176217, 2674.665666791651, 2544.669193004907, 2555.092942005589, 2559.2322303308933, 2566.2814411964678, 2567.8844451999703, 2534.3790771260974, 2538.2765911715073, 2548.6559314492492, 2548.9490210195877, 2553.093651753638, 13769.380377883019, 14052.718945621133, 14061.437542498288, 14062.0, 14071.226082565454, 7379.52033117851, 7623.847523049563, 7657.387409007321, 7657.885131723028, 7662.387009039264, 4878.878028049292, 5024.62315326352, 5060.493950604947, 5135.204043373283, 5148.304978827848, 1911.6081203353278, 1921.8942958137297, 1922.9230830766753, 1959.6668566343756, 1968.7342208801772, 22747.88626056859, 22954.77045229543, 23050.92541671241, 23082.42293942648, 23106.30681079587, 6165.506759459322, 6397.136386587844, 6430.839229019312, 6531.902021469682, 6567.277599464055, 15880.20598970047, 15999.440019599258, 16012.439564615182, 16018.839811601734, 16032.519024429286, 7260.4554658399775, 7334.4499162563025, 7405.740799072114, 7421.332080112816, 7423.9257607424315, 5679.403662615366, 5961.165436838846, 6221.4089661482085, 6235.937640623536, 6820.181578210647, 4824.975875120672, 4958.388523729802, 5036.345275114285, 5048.671836330626, 5056.191009438472, 1353.8984576156827, 1370.4518192722792, 1379.9103058301177, 1390.7635701930697, 1400.0689541455022, 914.1863741270275, 939.793245485992, 941.5621735892706, 950.4344914775697, 953.323140570189, 7402.518836275625, 7462.589557574385, 7469.402447804163, 7470.738524151629, 7508.849823003509, 646.4957333280049, 664.9667516624246, 669.6183175589867, 671.1141293492641, 680.8502129531495, 5678.375378708339, 5727.799527016535, 5768.221290125864, 5805.651660900359, 5937.970310148422, 19548.70676939847, 20315.0, 20419.28532501385, 20438.59122817535, 20439.8978005112, 19276.132574034204, 19699.11353989074, 19778.70331945022, 19812.60374792496, 19995.40013799559, 10916.0, 11060.612878549213, 11083.334999900031, 11083.556657733601, 11140.49867755953, 7939.603019848869, 8010.479318844256, 8549.957250213709, 8601.698940537051, 8802.38383313162, 17304.567385815455, 17356.739648905022, 17445.04052277137, 17448.73826892623, 18261.360852370104, 17728.734068989233, 17815.198316075548, 18216.271349145885, 18299.54251143732, 18463.63072738511, 10726.356418614596, 10972.0, 10990.670279891616, 10997.175211859303, 11016.504257308601, 13072.215667060007, 13301.600951971457, 13408.061435699407, 13415.731685366432, 13452.730945380841, 10158.187345012233, 10255.538500767334, 10302.124319432933, 10312.123469504875, 10465.633702820365, 10632.096271816983, 10682.145428365557, 10698.62554810578, 10843.67468975932, 11122.499487522919, 9274.304427167854, 9280.802491864393, 9381.0, 9385.343025988115, 9400.15398614128, 7126.893096603659, 7198.856022879618, 7248.275172482709, 7360.1535823381, 7525.3227209551405, 7164.319389657974, 7203.819904502429, 7291.0, 7292.380147687506, 7333.449991250571], "value": 6329.339760759465, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.9325091112699, 502.1137691973666, 523.3066187301824, 528.4134610582255, 534.8152795829038, 6165.414285642868, 6165.599236049654, 6193.411625895544, 6204.937950620492, 6269.7492100315985, 6019.578629495937, 6046.946292122483, 6076.7569297228165, 6102.237220347457, 6208.534359923001, 4873.683210591318, 4950.529699678525, 4982.825601103962, 5065.772040258197, 5078.720670363128, 4958.181899986498, 4964.925526117111, 4978.406909788869, 5015.673981191229, 5023.246513023039, 4909.705417674933, 4921.753912304388, 4927.113119638464, 4947.727874966876, 5000.32495613093, 4842.782074806624, 4902.558769710708, 4936.33359496467, 4956.355673762388, 4972.900541989158, 4458.08609235106, 4460.8773858299, 4464.486584042812, 4502.41468609082, 4507.549245075499, 5917.319508256563, 5937.198478205431, 5967.4186340619335, 6014.939850601526, 6071.241094863142, 5600.355959064719, 5698.8290351289525, 5721.914171287434, 5733.3406658234, 5735.225744524478, 6481.967590162066, 6515.739370425177, 6554.24626167992, 6556.639384833832, 6571.44142747867, 4854.446577095331, 4856.587190088847, 4893.290472881448, 4896.363472748555, 4911.582515486188, 6529.020646903003, 6531.530405658688, 6543.378379053982, 6781.389674929231, 6896.27589103147, 5002.299678045077, 5020.146575082244, 5028.547430731179, 5028.899422011613, 5037.093323201786, 5067.138586440314, 5088.465711100355, 5095.821646242363, 5114.55300105394, 5114.948850511447, 5646.0, 5669.5005844330935, 5677.545796336283, 5691.4877661010705, 5691.658500489901, 6544.803655890331, 6596.439302659328, 6607.834804129839, 6624.304448032984, 6627.668616569153, 1772.6543324051686, 1772.97340539892, 1784.0, 1797.5146710388121, 1797.7662903822682, 1165.8367828504017, 1201.4233168079197, 1218.4638758946212, 1222.4926655437894, 1232.8952039076778, 5697.373288938214, 5728.427157284238, 5757.65454072757, 5795.203486919068, 5834.562407819346, 6587.275399706028, 6648.501362397839, 6655.800325990228, 6666.96666516674, 6741.865032759692, 5661.207430959669, 5671.808920126737, 5733.88532229353, 5790.507806836382, 5804.970975145097, 3552.3605750964557, 3573.446115852047, 3602.5857026442295, 3612.3317186320614, 3613.0967258185456, 6475.740970361133, 6486.54594178403, 6547.377999090078, 6573.488097737451, 6586.5389422739945, 6803.7618683345845, 6829.590224586541, 6841.93158068413, 6853.965730171317, 6948.791536253921, 6448.9032664511005, 6481.611103333815, 6545.509086818508, 6548.345165483413, 6608.4052435281055, 6689.565178263398, 6741.898871516833, 6753.696083676345, 6765.932340676531, 6767.864642707217, 6103.206583144163, 6505.381988711065, 6600.735970561123, 6615.437687796399, 6623.688155922039, 6410.647414392253, 6450.645214513059, 6450.838729031811, 6504.6747662615735, 6504.739810407531, 5016.573591244639, 5021.297018417497, 5028.4971502850885, 5031.396232452129, 5055.797768089235, 6465.547411681137, 6502.349765023649, 6588.571742836701, 6592.208934927839, 6650.866982660416, 6400.455961243347, 6425.935740642533, 6457.580257283262, 6530.510211734234, 6544.738210471528, 6495.642739649175, 6506.0, 6508.056331831764, 6555.115059467102, 6590.901136483052, 6382.319735435373, 6399.232092148785, 6419.935800641933, 6490.837729056811, 6502.187226596676, 6533.575317604531, 6542.313057129123, 6616.8345791355605, 6646.368594983469, 6692.330766923269, 6348.714307856065, 6381.010943303795, 6428.614283143026, 6450.8064758057335, 6470.417662410406], "value": 5042.81683580849, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [885.7209978856664, 904.6607522179182, 911.1162172692489, 917.9357444978854, 927.2767241551592, 17709.64580708385, 18859.62280754386, 19626.509337266558, 19707.802921970775, 19836.60326793463, 16087.678246435064, 20672.0, 21003.894980525114, 21155.682664760003, 21260.8936955315, 1044.2064031336201, 1066.845307430426, 1067.7864427114569, 1071.4160782373585, 1072.1369297715337, 1048.5910494906993, 1058.740608550906, 1059.4120263253892, 1059.5814653211976, 1060.188955449082, 1056.4242487844106, 1093.6937657455885, 1098.1434481104711, 1105.701460605636, 1123.3933675815051, 1090.0516550600948, 1090.1714696830427, 1090.3566895531674, 1090.8363745438169, 1093.316677076827, 794.793353728029, 801.286854699318, 801.7835184500152, 801.8476489467041, 801.875709265059, 19688.212471501123, 20180.29368972079, 20213.19147234109, 20234.0, 20245.49386265355, 21450.249241276637, 21664.025118869533, 21684.674729879065, 22165.778342216534, 22200.333989980325, 16265.756013659804, 16382.0, 16591.91704041472, 16593.6681266374, 16733.16334183286, 3634.309481198564, 3652.0, 3653.1780349421433, 3654.543182102237, 3655.5065066216252, 128284.0, 128534.0, 129160.0, 133820.0, 134612.0, 16637.417690381022, 16757.41349052777, 17041.14794260282, 17141.400050998156, 17214.827851721573, 29852.0, 29924.401511969638, 30204.24489387783, 30848.383032339676, 30868.845655771576, 7874.330681891989, 8171.877421838677, 8497.40518163735, 8553.828923421497, 8846.380753347205, 4376.234159022159, 4484.977575112103, 4491.797869095899, 4499.730016199039, 4506.774661266989, 5056.974715126401, 5131.923021154686, 5135.537801597874, 5150.9227361588855, 5157.277981082651, 959.472290240365, 976.8241716490958, 993.5330394714473, 995.2187532786728, 996.2926322311096, 675.8209074595236, 685.2085840853822, 703.4337358426413, 713.6146480900255, 716.2837162837163, 11938.286399041412, 12185.207961482476, 12193.292938988548, 12352.938235308946, 13167.473301068041, 668.2582333609787, 669.8760729265101, 681.2268075734078, 684.8356394465392, 697.8988046733195, 5937.346891841894, 5951.721487780632, 6011.729472173763, 6035.969820150871, 6049.576529642971, 107778.46110769396, 108809.0, 111318.44340778244, 112636.43681781703, 113157.0, 94444.0, 96869.0, 97405.02594974102, 97453.0, 102436.0, 84055.15944840596, 85786.57106714552, 87461.56269218613, 87473.1252687465, 87513.12486875177, 10179.7455063624, 10613.893861061599, 10618.628348007782, 10761.139108871115, 10991.340519568852, 37701.0, 37957.24085518329, 38157.80921095377, 39136.608633913296, 40005.1998960025, 48551.75724121357, 49365.50634493609, 49926.0, 50359.496405036945, 50990.490095098576, 15044.924775376054, 15302.770458443354, 15317.846821531642, 15432.84567154314, 15514.689706206038, 17626.91186544059, 19197.0, 20158.79841201569, 20164.79835201688, 20358.0, 18630.44108676742, 18735.156917939, 18747.15637796275, 18815.717764233275, 18957.90521047386, 17182.656346872744, 17191.65616687684, 17216.225269862636, 17372.044537550555, 17625.64748704993, 18334.72497912559, 18460.0, 18491.907540462213, 18493.53766155857, 18779.71830422572, 11590.826137607773, 11795.823062653895, 11954.521819127485, 12011.339376334035, 12069.094817888517, 13690.589282321545, 13950.720985580028, 13955.790663140264, 13970.860291396955, 14113.0], "value": 9032.763251387623, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1542.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 108.0, 106.0, 118.0, 108.0, 111.0, 108.0, 99.0, 112.0, 111.0], "value": 108.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 121.0, 147.0, 189.0, 119.0, 151.0, 146.0, 120.0, 179.0, 119.0], "value": 144.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 162.0, 163.0, 162.0, 165.0, 169.0, 161.0, 169.0], "value": 163.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 78.0, 187.0, 77.0, 75.0, 77.0, 74.0, 155.0, 79.0], "value": 95.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 139.0, 79.0, 85.0, 79.0, 129.0, 79.0, 79.0, 153.0], "value": 98.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 122.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 115.0, 122.0], "value": 116.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 150.0, 156.0, 152.0, 155.0, 150.0, 150.0, 150.0, 152.0], "value": 151.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 99.0, 95.0, 94.0, 94.0, 94.0, 94.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 53.0, 56.0, 58.0, 59.0, 54.0, 53.0, 53.0, 55.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 60.0, 62.0, 59.0, 61.0, 58.0, 62.0, 56.0, 54.0], "value": 58.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 76.0, 77.0, 75.0, 75.0, 75.0, 75.0, 77.0, 81.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 122.0, 132.0, 130.0, 130.0, 124.0, 128.0, 126.0, 124.0, 170.0], "value": 132.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 200.0, 173.0, 177.0, 171.0, 173.0, 172.0, 187.0, 185.0, 181.0], "value": 179.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 72.0, 66.0, 71.0, 66.0, 67.0, 68.0, 70.0, 66.0, 70.0], "value": 69.1, "unit": "score"}]}]} 16:22:41 INFO - Return code: 0 16:22:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:22:41 INFO - # TBPL SUCCESS # 16:22:41 INFO - Running post-action listener: _package_coverage_data 16:22:41 INFO - Running post-action listener: _resource_record_post_action 16:22:41 INFO - [mozharness: 2017-08-17 23:22:41.254451Z] Finished run-tests step (success) 16:22:41 INFO - Running post-run listener: _resource_record_post_run 16:22:41 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 7458816; Write bytes: 441909248; Read time: 3480; Write time: 364632 16:22:41 INFO - TinderboxPrint: CPU usage
13.1% 16:22:41 INFO - TinderboxPrint: I/O read bytes / time
7,458,816 / 3,480 16:22:41 INFO - TinderboxPrint: I/O write bytes / time
441,909,248 / 364,632 16:22:41 INFO - TinderboxPrint: CPU idle
8,289.4 (86.9%) 16:22:41 INFO - TinderboxPrint: CPU user
1,200.2 (12.6%) 16:22:41 INFO - TinderboxPrint: Swap in / out
0 / 0 16:22:41 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 120532992; Read time: 124; Write time: 79540 16:22:41 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:22:41 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 5423104; Write bytes: 278495232; Read time: 3084; Write time: 270968 16:22:41 INFO - Running post-run listener: _upload_blobber_files 16:22:41 INFO - Blob upload gear active. 16:22:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:22:41 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:22:41 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:22:41 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:22:42 INFO - (blobuploader) - INFO - Open directory for files ... 16:22:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:22:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:22:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:43 INFO - (blobuploader) - INFO - Done attempting. 16:22:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:22:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:22:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:44 INFO - (blobuploader) - INFO - Done attempting. 16:22:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:22:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:22:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:51 INFO - (blobuploader) - INFO - Done attempting. 16:22:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:22:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:22:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:22:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:22:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:22:52 INFO - (blobuploader) - INFO - Done attempting. 16:22:52 INFO - (blobuploader) - INFO - Iteration through files over. 16:22:52 INFO - Return code: 0 16:22:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:22:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:22:52 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9d5ba65abc479fab4dfac8ef7a7a4cf7d76db208b7243e2e39c28a6ba679c839eb552c70d4515ba6014f2f085587be5fc12ca80928820c547c2fe5d9d38883", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68be5bc777ff056d1c6d62b758d5ccdb9b7c28e687b33299babc7afc6a143a0a76a5b1b57ff328ceb6d6eb8b64ccb28c7e3c1a6af799de2f0f854c75b1b241dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e43a3eee7ea1d8902f56ad38b431013785e597319877a9175ea177ba2252dc8c33bce995ff705ca12902ada43bc18d14d16bddf19d930ee2ab1207914744f59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed76723bffb33a8f7f3d6b318415ab8929c96c2b5d131a2f467da4fdd203eafa4a18a0449b6a197957f5eec18a68bb0cd800837481d5385ae065b55de2bcc7b9"} 16:22:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:22:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:22:52 INFO - Contents: 16:22:52 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9d5ba65abc479fab4dfac8ef7a7a4cf7d76db208b7243e2e39c28a6ba679c839eb552c70d4515ba6014f2f085587be5fc12ca80928820c547c2fe5d9d38883", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68be5bc777ff056d1c6d62b758d5ccdb9b7c28e687b33299babc7afc6a143a0a76a5b1b57ff328ceb6d6eb8b64ccb28c7e3c1a6af799de2f0f854c75b1b241dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e43a3eee7ea1d8902f56ad38b431013785e597319877a9175ea177ba2252dc8c33bce995ff705ca12902ada43bc18d14d16bddf19d930ee2ab1207914744f59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed76723bffb33a8f7f3d6b318415ab8929c96c2b5d131a2f467da4fdd203eafa4a18a0449b6a197957f5eec18a68bb0cd800837481d5385ae065b55de2bcc7b9"} 16:22:52 INFO - Running post-run listener: copy_logs_to_upload_dir 16:22:52 INFO - Copying logs to upload dir... 16:22:52 INFO - mkdir: /builds/slave/test/build/upload/logs 16:22:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.865974 ========= master_lag: 0.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-08-17 16:22:52.403611) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-17 16:22:52.408167) ========= 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=d2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9d5ba65abc479fab4dfac8ef7a7a4cf7d76db208b7243e2e39c28a6ba679c839eb552c70d4515ba6014f2f085587be5fc12ca80928820c547c2fe5d9d38883", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68be5bc777ff056d1c6d62b758d5ccdb9b7c28e687b33299babc7afc6a143a0a76a5b1b57ff328ceb6d6eb8b64ccb28c7e3c1a6af799de2f0f854c75b1b241dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e43a3eee7ea1d8902f56ad38b431013785e597319877a9175ea177ba2252dc8c33bce995ff705ca12902ada43bc18d14d16bddf19d930ee2ab1207914744f59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed76723bffb33a8f7f3d6b318415ab8929c96c2b5d131a2f467da4fdd203eafa4a18a0449b6a197957f5eec18a68bb0cd800837481d5385ae065b55de2bcc7b9"} build_url:https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011694 build_url: 'https://queue.taskcluster.net/v1/task/aWkJAhYOSgqx_drvisgeVA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9d5ba65abc479fab4dfac8ef7a7a4cf7d76db208b7243e2e39c28a6ba679c839eb552c70d4515ba6014f2f085587be5fc12ca80928820c547c2fe5d9d38883", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68be5bc777ff056d1c6d62b758d5ccdb9b7c28e687b33299babc7afc6a143a0a76a5b1b57ff328ceb6d6eb8b64ccb28c7e3c1a6af799de2f0f854c75b1b241dc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e43a3eee7ea1d8902f56ad38b431013785e597319877a9175ea177ba2252dc8c33bce995ff705ca12902ada43bc18d14d16bddf19d930ee2ab1207914744f59", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed76723bffb33a8f7f3d6b318415ab8929c96c2b5d131a2f467da4fdd203eafa4a18a0449b6a197957f5eec18a68bb0cd800837481d5385ae065b55de2bcc7b9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-17 16:22:52.439541) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-17 16:22:52.440154) ========= 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=d2a8c569965ab8367867b893000001a1-1503010895.705866-1670886633 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004487 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-17 16:22:52.522734) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-17 16:22:52.523066) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-17 16:22:52.523429) ========= ========= Total master_lag: 0.38 =========