builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-044 starttime: 1502193949.42 results: success (0) revision: 8c7788eda00e4cb4445638bdb7500009cd4cbe59 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-08 05:05:49.424025) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-08 05:05:49.424405) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-08 05:05:49.424679) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-08 05:05:49.487840) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:05:49.488143) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-08 05:05:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 36.8M=0s 2017-08-08 05:05:50 (36.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.149717 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:05:49.651342) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:05:49.651677) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019887 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:05:49.708091) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:05:49.708388) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8c7788eda00e4cb4445638bdb7500009cd4cbe59 --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 8c7788eda00e4cb4445638bdb7500009cd4cbe59 --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=88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-08 05:05:50,342 truncating revision to first 12 chars 2017-08-08 05:05:50,342 Setting DEBUG logging. 2017-08-08 05:05:50,342 attempt 1/10 2017-08-08 05:05:50,342 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8c7788eda00e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-08 05:05:50,644 unpacking tar archive at: mozilla-central-8c7788eda00e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.669473 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:05:50.391778) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-08 05:05:50.392081) ========= script_repo_revision: 8c7788eda00e4cb4445638bdb7500009cd4cbe59 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-08 05:05:50.392470) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-08 05:05:50.392735) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-08 05:05:50.407169) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 59 secs) (at 2017-08-08 05:05:50.407454) ========= /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=88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:05:51 INFO - MultiFileLogger online at 20170808 05:05:51 in /builds/slave/test 05:05:51 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 05:05:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:05:51 INFO - {'append_to_log': False, 05:05:51 INFO - 'base_work_dir': '/builds/slave/test', 05:05:51 INFO - 'blob_upload_branch': 'Firefox', 05:05:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:05:51 INFO - 'branch': 'Firefox', 05:05:51 INFO - 'buildbot_json_path': 'buildprops.json', 05:05:51 INFO - 'code_coverage': False, 05:05:51 INFO - 'config_files': ('talos/linux_config.py',), 05:05:51 INFO - 'default_actions': ('clobber', 05:05:51 INFO - 'read-buildbot-config', 05:05:51 INFO - 'download-and-extract', 05:05:51 INFO - 'populate-webroot', 05:05:51 INFO - 'create-virtualenv', 05:05:51 INFO - 'install', 05:05:51 INFO - 'setup-mitmproxy', 05:05:51 INFO - 'run-tests'), 05:05:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:05:51 INFO - 'disable_ccov_upload': False, 05:05:51 INFO - 'download_minidump_stackwalk': True, 05:05:51 INFO - 'download_symbols': 'ondemand', 05:05:51 INFO - 'enable_stylo': False, 05:05:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:05:51 INFO - 'tooltool.py': '/tools/tooltool.py', 05:05:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:05:51 INFO - '/tools/misc-python/virtualenv.py')}, 05:05:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:05:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:05:51 INFO - 'gecko_profile': False, 05:05:51 INFO - 'gecko_profile_interval': 0, 05:05:51 INFO - 'installer_path': 'installer.exe', 05:05:51 INFO - 'log_level': 'info', 05:05:51 INFO - 'log_name': 'talos', 05:05:51 INFO - 'log_to_console': True, 05:05:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:05:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:05:51 INFO - 'opt_config_files': (), 05:05:51 INFO - 'pip_index': False, 05:05:51 INFO - 'suite': 'dromaeojs-e10s', 05:05:51 INFO - 'system_bits': '32', 05:05:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:05:51 INFO - 'title': 'talos-linux64-ix-044', 05:05:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:05:51 INFO - 'use_talos_json': True, 05:05:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:05:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:05:51 INFO - 'work_dir': 'build'} 05:05:51 INFO - [mozharness: 2017-08-08 12:05:51.152359Z] Running clobber step. 05:05:51 INFO - Running pre-action listener: _resource_record_pre_action 05:05:51 INFO - Running main action method: clobber 05:05:51 INFO - rmtree: /builds/slave/test/build 05:05:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:05:52 INFO - Running post-action listener: _resource_record_post_action 05:05:52 INFO - [mozharness: 2017-08-08 12:05:52.187722Z] Finished clobber step (success) 05:05:52 INFO - [mozharness: 2017-08-08 12:05:52.187823Z] Running read-buildbot-config step. 05:05:52 INFO - Running pre-action listener: _resource_record_pre_action 05:05:52 INFO - Running main action method: read_buildbot_config 05:05:52 INFO - Using buildbot properties: 05:05:52 INFO - { 05:05:52 INFO - "project": "", 05:05:52 INFO - "product": "firefox", 05:05:52 INFO - "who": "cbook@mozilla.com", 05:05:52 INFO - "installer_path": "public/build/target.tar.bz2", 05:05:52 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 05:05:52 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:05:52 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 05:05:52 INFO - "stage_platform": "linux64-stylosequential", 05:05:52 INFO - "basedir": "/builds/slave/test", 05:05:52 INFO - "buildnumber": 23, 05:05:52 INFO - "platform": "ubuntu64_hw_styloseq", 05:05:52 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:05:52 INFO - "slavebuilddir": "test", 05:05:52 INFO - "taskId": "Z3X3qZSsSBu5DOCydZ69tg", 05:05:52 INFO - "branch": "mozilla-central", 05:05:52 INFO - "script_repo_revision": "production", 05:05:52 INFO - "revision": "8c7788eda00e4cb4445638bdb7500009cd4cbe59", 05:05:52 INFO - "slavename": "talos-linux64-ix-044", 05:05:52 INFO - "repo_path": "mozilla-central" 05:05:52 INFO - } 05:05:52 INFO - Finding installer, test and symbols from parent task. 05:05:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z3X3qZSsSBu5DOCydZ69tg'}, attempt #1 05:05:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z3X3qZSsSBu5DOCydZ69tg'}, attempt #1 05:05:53 INFO - Task dependencies: At2ih0kyQ7Ka5Inf4WhGAA 05:05:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA'}, attempt #1 05:05:53 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.tar.bz2 05:05:53 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.test_packages.json 05:05:53 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.crashreporter-symbols.zip 05:05:53 INFO - Running post-action listener: _resource_record_post_action 05:05:53 INFO - [mozharness: 2017-08-08 12:05:53.542852Z] Finished read-buildbot-config step (success) 05:05:53 INFO - [mozharness: 2017-08-08 12:05:53.543075Z] Running download-and-extract step. 05:05:53 INFO - Running pre-action listener: _resource_record_pre_action 05:05:53 INFO - Running main action method: download_and_extract 05:05:53 INFO - mkdir: /builds/slave/test/build/tests 05:05:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:53 INFO - trying https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.test_packages.json 05:05:53 INFO - Downloading https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:05:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:05:54 INFO - Downloaded 989 bytes. 05:05:54 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:05:54 INFO - Using the following test package requirements: 05:05:54 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:05:54 INFO - u'common': [u'target.common.tests.zip'], 05:05:54 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:05:54 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:05:54 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:05:54 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:05:54 INFO - u'mozbase': [u'target.common.tests.zip'], 05:05:54 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:05:54 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:05:54 INFO - u'web-platform': [u'target.common.tests.zip', 05:05:54 INFO - u'target.web-platform.tests.tar.gz'], 05:05:54 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:05:54 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:05:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.common.tests.zip 05:05:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:05:54 INFO - Fetch https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.common.tests.zip into memory 05:05:56 INFO - Content-Length response header: 54284886 05:05:56 INFO - Bytes received: 54284886 05:06:07 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:06:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.common.tests.zip 05:06:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:06:07 INFO - Fetch https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.common.tests.zip into memory 05:06:09 INFO - Content-Length response header: 54284886 05:06:09 INFO - Bytes received: 54284886 05:06:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.talos.tests.zip 05:06:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:06:21 INFO - Fetch https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.talos.tests.zip into memory 05:06:22 INFO - Content-Length response header: 14496008 05:06:22 INFO - Bytes received: 14496008 05:06:22 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')]} 05:06:22 INFO - trying https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.tar.bz2 05:06:22 INFO - Downloading https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:06:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:06:23 INFO - Downloaded 61790054 bytes. 05:06:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.tar.bz2 05:06:23 INFO - mkdir: /builds/slave/test/properties 05:06:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:06:23 INFO - Writing to file /builds/slave/test/properties/build_url 05:06:23 INFO - Contents: 05:06:23 INFO - build_url:https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.tar.bz2 05:06:23 INFO - Running post-action listener: _resource_record_post_action 05:06:23 INFO - Running post-action listener: set_extra_try_arguments 05:06:23 INFO - [mozharness: 2017-08-08 12:06:23.497941Z] Finished download-and-extract step (success) 05:06:23 INFO - [mozharness: 2017-08-08 12:06:23.498040Z] Running populate-webroot step. 05:06:23 INFO - Running pre-action listener: _resource_record_pre_action 05:06:23 INFO - Running main action method: populate_webroot 05:06:23 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:06:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:06:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:06:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:06:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:06:23 INFO - 'tests': ['damp', 'tps']}, 05:06:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:06:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:06:23 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:06:23 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:06:23 INFO - 'other-e10s': {'tests': ['a11yr', 05:06:23 INFO - 'ts_paint', 05:06:23 INFO - 'tpaint', 05:06:23 INFO - 'sessionrestore', 05:06:23 INFO - 'sessionrestore_many_windows', 05:06:23 INFO - 'sessionrestore_no_auto_restore', 05:06:23 INFO - 'tabpaint']}, 05:06:23 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:06:23 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:06:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:06:23 INFO - 'tsvgr_opacity', 05:06:23 INFO - 'tart', 05:06:23 INFO - 'tscrollx', 05:06:23 INFO - 'tsvg_static']}, 05:06:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:06:23 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:06:23 INFO - 'talos_options': ['--mitmproxy', 05:06:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:06:23 INFO - '--firstNonBlankPaint'], 05:06:23 INFO - 'tests': ['tp6_google', 05:06:23 INFO - 'tp6_youtube', 05:06:23 INFO - 'tp6_amazon', 05:06:23 INFO - 'tp6_facebook']}, 05:06:23 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:06:23 INFO - 'talos_options': ['--stylo', 05:06:23 INFO - '--mitmproxy', 05:06:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:06:23 INFO - '--firstNonBlankPaint'], 05:06:23 INFO - 'tests': ['tp6_google', 05:06:23 INFO - 'tp6_youtube', 05:06:23 INFO - 'tp6_amazon', 05:06:23 INFO - 'tp6_facebook']}, 05:06:23 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:06:23 INFO - 'talos_options': ['--stylo', 05:06:23 INFO - '--stylo-threads=1', 05:06:23 INFO - '--mitmproxy', 05:06:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:06:23 INFO - '--firstNonBlankPaint'], 05:06:23 INFO - 'tests': ['tp6_google', 05:06:23 INFO - 'tp6_youtube', 05:06:23 INFO - 'tp6_amazon', 05:06:23 INFO - 'tp6_facebook']}, 05:06:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:06:23 INFO - 'talos_options': ['--xperf_path', 05:06:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:06:23 INFO - 'tests': ['tp5n']}}} 05:06:23 INFO - Running post-action listener: _resource_record_post_action 05:06:23 INFO - [mozharness: 2017-08-08 12:06:23.506578Z] Finished populate-webroot step (success) 05:06:23 INFO - [mozharness: 2017-08-08 12:06:23.506669Z] Running create-virtualenv step. 05:06:23 INFO - Running pre-action listener: _resource_record_pre_action 05:06:23 INFO - Running main action method: create_virtualenv 05:06:23 INFO - Creating virtualenv /builds/slave/test/build/venv 05:06:23 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 05:06:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:06:23 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:06:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:06:23 INFO - Using real prefix '/usr' 05:06:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:06:24 INFO - Installing distribute.............................................................................................................................................................................................done. 05:06:26 INFO - Installing pip.................done. 05:06:26 INFO - Return code: 0 05:06:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:06:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:06:26 INFO - Reading from file tmpfile_stdout 05:06:26 INFO - Output received: 05:06:26 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) 05:06:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:06:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:26 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 0x21131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2482d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247feb0>, '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 0x2480db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', '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 05:06:26 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 05:06:26 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 05:06:26 INFO - Using env: {'DISPLAY': ':0', 05:06:26 INFO - 'HOME': '/home/cltbld', 05:06:26 INFO - 'LANG': 'en_US.UTF-8', 05:06:26 INFO - 'LANGUAGE': 'en_US:en', 05:06:26 INFO - 'LOGNAME': 'cltbld', 05:06:26 INFO - 'MAIL': '/var/mail/cltbld', 05:06:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:26 INFO - 'MOZ_NO_REMOTE': '1', 05:06:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:26 INFO - 'NO_EM_RESTART': '1', 05:06:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:26 INFO - 'PWD': '/builds/slave/test', 05:06:26 INFO - 'SHELL': '/bin/bash', 05:06:26 INFO - 'SHLVL': '1', 05:06:26 INFO - 'TERM': 'linux', 05:06:26 INFO - 'TMOUT': '86400', 05:06:26 INFO - 'USER': 'cltbld', 05:06:26 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', 05:06:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:26 INFO - '_': '/tools/buildbot/bin/python'} 05:06:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:26 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 05:06:26 INFO - Cleaning up... 05:06:26 INFO - Return code: 0 05:06:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:06:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:26 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 0x21131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2482d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247feb0>, '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 0x2480db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', '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 05:06:26 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 05:06:26 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 05:06:26 INFO - Using env: {'DISPLAY': ':0', 05:06:26 INFO - 'HOME': '/home/cltbld', 05:06:26 INFO - 'LANG': 'en_US.UTF-8', 05:06:26 INFO - 'LANGUAGE': 'en_US:en', 05:06:26 INFO - 'LOGNAME': 'cltbld', 05:06:26 INFO - 'MAIL': '/var/mail/cltbld', 05:06:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:26 INFO - 'MOZ_NO_REMOTE': '1', 05:06:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:26 INFO - 'NO_EM_RESTART': '1', 05:06:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:26 INFO - 'PWD': '/builds/slave/test', 05:06:26 INFO - 'SHELL': '/bin/bash', 05:06:26 INFO - 'SHLVL': '1', 05:06:26 INFO - 'TERM': 'linux', 05:06:26 INFO - 'TMOUT': '86400', 05:06:26 INFO - 'USER': 'cltbld', 05:06:26 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', 05:06:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:26 INFO - '_': '/tools/buildbot/bin/python'} 05:06:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:26 INFO - Downloading/unpacking psutil>=3.1.1 05:06: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 05:06: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 05:06: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 05:06: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 05:06:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:06:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:06:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:06:27 INFO - Installing collected packages: psutil 05:06:27 INFO - Running setup.py install for psutil 05:06:28 INFO - building 'psutil._psutil_linux' extension 05:06:28 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 05:06: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 05:06:28 INFO - building 'psutil._psutil_posix' extension 05:06: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 05:06: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 05:06:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:06:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:06:28 INFO - Successfully installed psutil 05:06:28 INFO - Cleaning up... 05:06:28 INFO - Return code: 0 05:06:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:06: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')]} 05:06:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06: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')]} 05:06:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06: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 0x21131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2482d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247feb0>, '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 0x2480db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', '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 05:06: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 05:06: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 05:06:28 INFO - Using env: {'DISPLAY': ':0', 05:06:28 INFO - 'HOME': '/home/cltbld', 05:06:28 INFO - 'LANG': 'en_US.UTF-8', 05:06:28 INFO - 'LANGUAGE': 'en_US:en', 05:06:28 INFO - 'LOGNAME': 'cltbld', 05:06:28 INFO - 'MAIL': '/var/mail/cltbld', 05:06:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:28 INFO - 'MOZ_NO_REMOTE': '1', 05:06:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:28 INFO - 'NO_EM_RESTART': '1', 05:06:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:28 INFO - 'PWD': '/builds/slave/test', 05:06:28 INFO - 'SHELL': '/bin/bash', 05:06:28 INFO - 'SHLVL': '1', 05:06:28 INFO - 'TERM': 'linux', 05:06:28 INFO - 'TMOUT': '86400', 05:06:28 INFO - 'USER': 'cltbld', 05:06:28 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', 05:06:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:28 INFO - '_': '/tools/buildbot/bin/python'} 05:06:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:06: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 05:06: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 05:06: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 05:06: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 05:06:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:06:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:06:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:06:29 INFO - Installing collected packages: mozsystemmonitor 05:06:29 INFO - Running setup.py install for mozsystemmonitor 05:06:29 INFO - Successfully installed mozsystemmonitor 05:06:29 INFO - Cleaning up... 05:06:29 INFO - Return code: 0 05:06:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:06:29 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')]} 05:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:29 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')]} 05:06:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06: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 0x21131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2482d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247feb0>, '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 0x2480db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', '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 05:06: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 05:06: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 05:06:30 INFO - Using env: {'DISPLAY': ':0', 05:06:30 INFO - 'HOME': '/home/cltbld', 05:06:30 INFO - 'LANG': 'en_US.UTF-8', 05:06:30 INFO - 'LANGUAGE': 'en_US:en', 05:06:30 INFO - 'LOGNAME': 'cltbld', 05:06:30 INFO - 'MAIL': '/var/mail/cltbld', 05:06:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:30 INFO - 'MOZ_NO_REMOTE': '1', 05:06:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:30 INFO - 'NO_EM_RESTART': '1', 05:06:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:30 INFO - 'PWD': '/builds/slave/test', 05:06:30 INFO - 'SHELL': '/bin/bash', 05:06:30 INFO - 'SHLVL': '1', 05:06:30 INFO - 'TERM': 'linux', 05:06:30 INFO - 'TMOUT': '86400', 05:06:30 INFO - 'USER': 'cltbld', 05:06:30 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', 05:06:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:30 INFO - '_': '/tools/buildbot/bin/python'} 05:06:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:30 INFO - Downloading/unpacking jsonschema==2.5.1 05:06: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 05:06: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 05:06: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 05:06: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 05:06:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:06:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:06: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 05:06: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 05:06: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 05:06: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 05:06:31 INFO - Downloading functools32-3.2.3-2.tar.gz 05:06:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:06:31 INFO - warning: no files found matching '*.txt' 05:06:31 INFO - no previously-included directories found matching 'build' 05:06:31 INFO - no previously-included directories found matching 'dist' 05:06:31 INFO - no previously-included directories found matching '.git*' 05:06:31 INFO - Installing collected packages: jsonschema, functools32 05:06:31 INFO - Running setup.py install for functools32 05:06:31 INFO - warning: no files found matching '*.txt' 05:06:31 INFO - no previously-included directories found matching 'build' 05:06:31 INFO - no previously-included directories found matching 'dist' 05:06:31 INFO - no previously-included directories found matching '.git*' 05:06:31 INFO - Successfully installed jsonschema functools32 05:06:31 INFO - Cleaning up... 05:06:31 INFO - Return code: 0 05:06:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:06: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')]} 05:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06: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')]} 05:06:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06: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 0x21131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2482d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247feb0>, '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 0x2480db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', '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 05:06: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 05:06: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 05:06:31 INFO - Using env: {'DISPLAY': ':0', 05:06:31 INFO - 'HOME': '/home/cltbld', 05:06:31 INFO - 'LANG': 'en_US.UTF-8', 05:06:31 INFO - 'LANGUAGE': 'en_US:en', 05:06:31 INFO - 'LOGNAME': 'cltbld', 05:06:31 INFO - 'MAIL': '/var/mail/cltbld', 05:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:31 INFO - 'MOZ_NO_REMOTE': '1', 05:06:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:31 INFO - 'NO_EM_RESTART': '1', 05:06:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:31 INFO - 'PWD': '/builds/slave/test', 05:06:31 INFO - 'SHELL': '/bin/bash', 05:06:31 INFO - 'SHLVL': '1', 05:06:31 INFO - 'TERM': 'linux', 05:06:31 INFO - 'TMOUT': '86400', 05:06:31 INFO - 'USER': 'cltbld', 05:06:31 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', 05:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:31 INFO - '_': '/tools/buildbot/bin/python'} 05:06:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:06:31 INFO - Cleaning up... 05:06:31 INFO - Return code: 0 05:06:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:06: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')]} 05:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06: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')]} 05:06:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06: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 0x21131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2482d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247feb0>, '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 0x2480db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', '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 05:06: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 05:06: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 05:06:32 INFO - Using env: {'DISPLAY': ':0', 05:06:32 INFO - 'HOME': '/home/cltbld', 05:06:32 INFO - 'LANG': 'en_US.UTF-8', 05:06:32 INFO - 'LANGUAGE': 'en_US:en', 05:06:32 INFO - 'LOGNAME': 'cltbld', 05:06:32 INFO - 'MAIL': '/var/mail/cltbld', 05:06:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:32 INFO - 'MOZ_NO_REMOTE': '1', 05:06:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:32 INFO - 'NO_EM_RESTART': '1', 05:06:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:32 INFO - 'PWD': '/builds/slave/test', 05:06:32 INFO - 'SHELL': '/bin/bash', 05:06:32 INFO - 'SHLVL': '1', 05:06:32 INFO - 'TERM': 'linux', 05:06:32 INFO - 'TMOUT': '86400', 05:06:32 INFO - 'USER': 'cltbld', 05:06:32 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', 05:06:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:32 INFO - '_': '/tools/buildbot/bin/python'} 05:06:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:32 INFO - Downloading/unpacking blobuploader==1.2.4 05:06: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 05:06: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 05:06: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 05:06: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 05:08:33 INFO - Cleaning up... 05:08:33 INFO - Exception: 05:08:33 ERROR - Traceback (most recent call last): 05:08:33 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg/pip/basecommand.py", line 122, in main 05:08:33 INFO - status = self.run(options, args) 05:08:33 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg/pip/commands/install.py", line 278, in run 05:08:33 INFO - requirement_set.prepare_files(finder, force_root_egg_info=self.bundle, bundle=self.bundle) 05:08:33 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg/pip/req.py", line 1177, in prepare_files 05:08:33 INFO - url = finder.find_requirement(req_to_install, upgrade=self.upgrade) 05:08:33 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg/pip/index.py", line 252, in find_requirement 05:08:33 INFO - for page in self._get_pages(locations, req): 05:08:33 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg/pip/index.py", line 387, in _get_pages 05:08:33 INFO - page = self._get_page(location, req) 05:08:33 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg/pip/index.py", line 568, in _get_page 05:08:33 INFO - session=self.session, 05:08:33 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg/pip/index.py", line 670, in get_page 05:08:33 INFO - resp = session.get(url, headers={"Accept": "text/html"}) 05:08:33 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg/pip/_vendor/requests/sessions.py", line 395, in get 05:08:33 INFO - return self.request('GET', url, **kwargs) 05:08:33 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg/pip/download.py", line 237, in request 05:08:33 INFO - return super(PipSession, self).request(method, url, *args, **kwargs) 05:08:33 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg/pip/_vendor/requests/sessions.py", line 383, in request 05:08:33 INFO - resp = self.send(prep, **send_kwargs) 05:08:33 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg/pip/_vendor/requests/sessions.py", line 506, in send 05:08:33 INFO - history = [resp for resp in gen] if allow_redirects else [] 05:08:33 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg/pip/_vendor/requests/sessions.py", line 168, in resolve_redirects 05:08:33 INFO - allow_redirects=False, 05:08:33 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg/pip/_vendor/requests/sessions.py", line 486, in send 05:08:33 INFO - r = adapter.send(request, **kwargs) 05:08:33 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg/pip/_vendor/requests/adapters.py", line 394, in send 05:08:33 INFO - r.content 05:08:33 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg/pip/_vendor/requests/models.py", line 679, in content 05:08:33 INFO - self._content = bytes().join(self.iter_content(CONTENT_CHUNK_SIZE)) or bytes() 05:08:33 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg/pip/_vendor/requests/models.py", line 616, in generate 05:08:33 INFO - decode_content=True): 05:08:33 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg/pip/_vendor/requests/packages/urllib3/response.py", line 236, in stream 05:08:33 INFO - data = self.read(amt=amt, decode_content=decode_content) 05:08:33 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg/pip/_vendor/requests/packages/urllib3/response.py", line 183, in read 05:08:33 INFO - data = self._fp.read(amt) 05:08:33 INFO - File "/usr/lib/python2.7/httplib.py", line 541, in read 05:08:33 INFO - return self._read_chunked(amt) 05:08:33 INFO - File "/usr/lib/python2.7/httplib.py", line 601, in _read_chunked 05:08:33 INFO - value.append(self._safe_read(chunk_left)) 05:08:33 INFO - File "/usr/lib/python2.7/httplib.py", line 647, in _safe_read 05:08:33 INFO - chunk = self.fp.read(min(amt, MAXAMOUNT)) 05:08:33 INFO - File "/usr/lib/python2.7/socket.py", line 380, in read 05:08:33 INFO - data = self._sock.recv(left) 05:08:33 INFO - timeout: timed out 05:08:33 INFO - Storing debug log for failure in /home/cltbld/.pip/pip.log 05:08:33 WARNING - Return code: 2 05:08:33 INFO - retry: Failed, sleeping 60 seconds before retrying 05:09:34 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 0x21131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2482d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247feb0>, '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 0x2480db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', '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 #2 05:09:34 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 05:09:34 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 05:09:34 INFO - Using env: {'DISPLAY': ':0', 05:09:34 INFO - 'HOME': '/home/cltbld', 05:09:34 INFO - 'LANG': 'en_US.UTF-8', 05:09:34 INFO - 'LANGUAGE': 'en_US:en', 05:09:34 INFO - 'LOGNAME': 'cltbld', 05:09:34 INFO - 'MAIL': '/var/mail/cltbld', 05:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:34 INFO - 'MOZ_NO_REMOTE': '1', 05:09:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:34 INFO - 'NO_EM_RESTART': '1', 05:09:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:34 INFO - 'PWD': '/builds/slave/test', 05:09:34 INFO - 'SHELL': '/bin/bash', 05:09:34 INFO - 'SHLVL': '1', 05:09:34 INFO - 'TERM': 'linux', 05:09:34 INFO - 'TMOUT': '86400', 05:09:34 INFO - 'USER': 'cltbld', 05:09:34 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', 05:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:34 INFO - '_': '/tools/buildbot/bin/python'} 05:09:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:34 INFO - Downloading/unpacking blobuploader==1.2.4 05:09:34 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 05:09:34 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 05:09:34 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 05:09:34 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 05:09:35 INFO - Downloading blobuploader-1.2.4.tar.gz 05:09:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:09:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:09:35 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 05:09:35 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 05:09:35 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 05:09:35 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 05:09:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:09:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:09:35 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 05:09:35 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 05:09:35 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 05:09:35 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 05:09:35 INFO - Downloading docopt-0.6.1.tar.gz 05:09:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:09:35 INFO - Installing collected packages: blobuploader, requests, docopt 05:09:35 INFO - Running setup.py install for blobuploader 05:09:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:09:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:09:36 INFO - Running setup.py install for requests 05:09:36 INFO - Running setup.py install for docopt 05:09:36 INFO - Successfully installed blobuploader requests docopt 05:09:36 INFO - Cleaning up... 05:09:36 INFO - Return code: 0 05:09:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:36 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 0x21131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2482d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247feb0>, '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 0x2480db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', '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 05:09:36 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 05:09:36 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 05:09:36 INFO - Using env: {'DISPLAY': ':0', 05:09:36 INFO - 'HOME': '/home/cltbld', 05:09:36 INFO - 'LANG': 'en_US.UTF-8', 05:09:36 INFO - 'LANGUAGE': 'en_US:en', 05:09:36 INFO - 'LOGNAME': 'cltbld', 05:09:36 INFO - 'MAIL': '/var/mail/cltbld', 05:09:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:36 INFO - 'MOZ_NO_REMOTE': '1', 05:09:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:36 INFO - 'NO_EM_RESTART': '1', 05:09:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:36 INFO - 'PWD': '/builds/slave/test', 05:09:36 INFO - 'SHELL': '/bin/bash', 05:09:36 INFO - 'SHLVL': '1', 05:09:36 INFO - 'TERM': 'linux', 05:09:36 INFO - 'TMOUT': '86400', 05:09:36 INFO - 'USER': 'cltbld', 05:09:36 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', 05:09:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:36 INFO - '_': '/tools/buildbot/bin/python'} 05:09:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:36 INFO - Running setup.py (path:/tmp/pip-FTm0bb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:36 INFO - Running setup.py (path:/tmp/pip-PPA88m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:36 INFO - Running setup.py (path:/tmp/pip-ovwh5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:36 INFO - Running setup.py (path:/tmp/pip-TbEHvW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:37 INFO - Running setup.py (path:/tmp/pip-uC0zN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:37 INFO - Running setup.py (path:/tmp/pip-OeYh28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:37 INFO - Running setup.py (path:/tmp/pip-BLKXOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:37 INFO - Running setup.py (path:/tmp/pip-F2VxcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:37 INFO - Running setup.py (path:/tmp/pip-hl_P5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:37 INFO - Running setup.py (path:/tmp/pip-ZqoZqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:37 INFO - Running setup.py (path:/tmp/pip-VpH8Gw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:37 INFO - Running setup.py (path:/tmp/pip-bqT2hW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:37 INFO - Running setup.py (path:/tmp/pip-UYJTNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:37 INFO - Running setup.py (path:/tmp/pip-_yKD4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:37 INFO - Running setup.py (path:/tmp/pip-4HEVYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:37 INFO - Running setup.py (path:/tmp/pip-Uc36i4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:37 INFO - Running setup.py (path:/tmp/pip-ikw_Fh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:09:37 INFO - Running setup.py install for manifestparser 05:09:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:09:38 INFO - Running setup.py install for mozcrash 05:09:38 INFO - Running setup.py install for mozdebug 05:09:38 INFO - Running setup.py install for mozdevice 05:09:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:09:38 INFO - Running setup.py install for mozfile 05:09:38 INFO - Running setup.py install for mozhttpd 05:09:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:09:38 INFO - Running setup.py install for mozinfo 05:09:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:09:38 INFO - Running setup.py install for mozInstall 05:09:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:09:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:09:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:09:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:09:38 INFO - Running setup.py install for mozleak 05:09:38 INFO - Running setup.py install for mozlog 05:09:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:09:39 INFO - Running setup.py install for moznetwork 05:09:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:09:39 INFO - Running setup.py install for mozprocess 05:09:39 INFO - Running setup.py install for mozprofile 05:09:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:09:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:09:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:09:39 INFO - Running setup.py install for mozrunner 05:09:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:09:39 INFO - Running setup.py install for mozscreenshot 05:09:39 INFO - Running setup.py install for moztest 05:09:39 INFO - Running setup.py install for mozversion 05:09:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:09:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:09:39 INFO - Cleaning up... 05:09:39 INFO - Return code: 0 05:09:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:39 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')]} 05:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:39 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')]} 05:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:39 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 0x21131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2482d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247feb0>, '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 0x2480db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', '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 05:09:39 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 05:09:39 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 05:09:39 INFO - Using env: {'DISPLAY': ':0', 05:09:39 INFO - 'HOME': '/home/cltbld', 05:09:39 INFO - 'LANG': 'en_US.UTF-8', 05:09:39 INFO - 'LANGUAGE': 'en_US:en', 05:09:39 INFO - 'LOGNAME': 'cltbld', 05:09:39 INFO - 'MAIL': '/var/mail/cltbld', 05:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:39 INFO - 'MOZ_NO_REMOTE': '1', 05:09:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:39 INFO - 'NO_EM_RESTART': '1', 05:09:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:39 INFO - 'PWD': '/builds/slave/test', 05:09:39 INFO - 'SHELL': '/bin/bash', 05:09:39 INFO - 'SHLVL': '1', 05:09:39 INFO - 'TERM': 'linux', 05:09:39 INFO - 'TMOUT': '86400', 05:09:39 INFO - 'USER': 'cltbld', 05:09:39 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', 05:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:39 INFO - '_': '/tools/buildbot/bin/python'} 05:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:39 INFO - Running setup.py (path:/tmp/pip-dQwUCO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:40 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)) 05:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:40 INFO - Running setup.py (path:/tmp/pip-Xt3EVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:40 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)) 05:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:40 INFO - Running setup.py (path:/tmp/pip-wjnGIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:40 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)) 05:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:40 INFO - Running setup.py (path:/tmp/pip-efOhC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:40 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)) 05:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:40 INFO - Running setup.py (path:/tmp/pip-LRyDik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:40 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)) 05:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:40 INFO - Running setup.py (path:/tmp/pip-Zr1Ww7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:40 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)) 05:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:40 INFO - Running setup.py (path:/tmp/pip-E691pF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:40 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)) 05:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:40 INFO - Running setup.py (path:/tmp/pip-gAOfUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:40 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)) 05:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:40 INFO - Running setup.py (path:/tmp/pip-e6x4P1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:40 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)) 05:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:40 INFO - Running setup.py (path:/tmp/pip-CAWm8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:40 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)) 05:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:40 INFO - Running setup.py (path:/tmp/pip-0aaywI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:40 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)) 05:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:40 INFO - Running setup.py (path:/tmp/pip-Dzxzkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:40 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)) 05:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:40 INFO - Running setup.py (path:/tmp/pip-qcl7iM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:40 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)) 05:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:40 INFO - Running setup.py (path:/tmp/pip-5WSL0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:40 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)) 05:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:40 INFO - Running setup.py (path:/tmp/pip-e5uVfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:41 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)) 05:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:41 INFO - Running setup.py (path:/tmp/pip-jkLvZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:41 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)) 05:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:41 INFO - Running setup.py (path:/tmp/pip-cS26XH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:41 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)) 05:09:41 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)) 05:09:41 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)) 05:09:41 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)) 05:09:41 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)) 05:09:41 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)) 05:09:41 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)) 05:09:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:09: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 05:09: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 05:09: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 05:09: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 05:09:42 INFO - Downloading blessings-1.6.tar.gz 05:09:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:09:42 INFO - Installing collected packages: blessings 05:09:42 INFO - Running setup.py install for blessings 05:09:42 INFO - Successfully installed blessings 05:09:42 INFO - Cleaning up... 05:09:42 INFO - Return code: 0 05:09:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:09:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:42 INFO - Reading from file tmpfile_stdout 05:09:42 INFO - Current package versions: 05:09:42 INFO - argparse == 1.2.1 05:09:42 INFO - blessings == 1.6 05:09:42 INFO - blobuploader == 1.2.4 05:09:42 INFO - docopt == 0.6.1 05:09:42 INFO - functools32 == 3.2.3-2 05:09:42 INFO - jsonschema == 2.5.1 05:09:42 INFO - manifestparser == 1.1 05:09:42 INFO - mozInstall == 1.13 05:09:42 INFO - mozcrash == 1.0 05:09:42 INFO - mozdebug == 0.1 05:09:42 INFO - mozdevice == 0.51 05:09:42 INFO - mozfile == 1.2 05:09:42 INFO - mozhttpd == 0.7 05:09:42 INFO - mozinfo == 0.10 05:09:42 INFO - mozleak == 0.1 05:09:42 INFO - mozlog == 3.5 05:09:42 INFO - moznetwork == 0.27 05:09:42 INFO - mozprocess == 0.25 05:09:42 INFO - mozprofile == 0.28 05:09:42 INFO - mozrunner == 6.13 05:09:42 INFO - mozscreenshot == 0.1 05:09:42 INFO - mozsystemmonitor == 0.3 05:09:42 INFO - moztest == 0.8 05:09:42 INFO - mozversion == 1.4 05:09:42 INFO - psutil == 3.1.1 05:09:42 INFO - requests == 1.2.3 05:09:42 INFO - wsgiref == 0.1.2 05:09:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:42 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 0x21131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2482d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247feb0>, '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 0x2480db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', '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 05:09:42 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 05:09:42 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 05:09:42 INFO - Using env: {'DISPLAY': ':0', 05:09:42 INFO - 'HOME': '/home/cltbld', 05:09:42 INFO - 'LANG': 'en_US.UTF-8', 05:09:42 INFO - 'LANGUAGE': 'en_US:en', 05:09:42 INFO - 'LOGNAME': 'cltbld', 05:09:42 INFO - 'MAIL': '/var/mail/cltbld', 05:09:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:42 INFO - 'MOZ_NO_REMOTE': '1', 05:09:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:42 INFO - 'NO_EM_RESTART': '1', 05:09:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:42 INFO - 'PWD': '/builds/slave/test', 05:09:42 INFO - 'SHELL': '/bin/bash', 05:09:42 INFO - 'SHLVL': '1', 05:09:42 INFO - 'TERM': 'linux', 05:09:42 INFO - 'TMOUT': '86400', 05:09:42 INFO - 'USER': 'cltbld', 05:09:42 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', 05:09:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:42 INFO - '_': '/tools/buildbot/bin/python'} 05:09:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:43 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)) 05:09:43 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)) 05:09:43 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)) 05:09:43 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)) 05:09:43 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)) 05:09:43 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)) 05:09:43 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)) 05:09:43 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)) 05:09:43 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)) 05:09:43 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:09:44 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)) 05:09:44 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)) 05:09:44 INFO - Installing collected packages: simplejson 05:09:44 INFO - Running setup.py install for simplejson 05:09:44 INFO - building 'simplejson._speedups' extension 05:09:44 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 05:09:45 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 05:09:45 INFO - Successfully installed simplejson 05:09:45 INFO - Cleaning up... 05:09:45 INFO - Return code: 0 05:09:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21131f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22dfa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2482d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x247feb0>, '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 0x2480db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481240>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', '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 05:09:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:09:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:09:45 INFO - Using env: {'DISPLAY': ':0', 05:09:45 INFO - 'HOME': '/home/cltbld', 05:09:45 INFO - 'LANG': 'en_US.UTF-8', 05:09:45 INFO - 'LANGUAGE': 'en_US:en', 05:09:45 INFO - 'LOGNAME': 'cltbld', 05:09:45 INFO - 'MAIL': '/var/mail/cltbld', 05:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:45 INFO - 'MOZ_NO_REMOTE': '1', 05:09:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:45 INFO - 'NO_EM_RESTART': '1', 05:09:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:45 INFO - 'PWD': '/builds/slave/test', 05:09:45 INFO - 'SHELL': '/bin/bash', 05:09:45 INFO - 'SHLVL': '1', 05:09:45 INFO - 'TERM': 'linux', 05:09:45 INFO - 'TMOUT': '86400', 05:09:45 INFO - 'USER': 'cltbld', 05:09:45 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', 05:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:45 INFO - '_': '/tools/buildbot/bin/python'} 05:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:09:45 INFO - Cleaning up... 05:09:45 INFO - Return code: 0 05:09:45 INFO - Running post-action listener: _resource_record_post_action 05:09:45 INFO - Running post-action listener: _start_resource_monitoring 05:09:45 INFO - Starting resource monitoring. 05:09:45 INFO - [mozharness: 2017-08-08 12:09:45.664597Z] Finished create-virtualenv step (success) 05:09:45 INFO - [mozharness: 2017-08-08 12:09:45.665111Z] Running install step. 05:09:45 INFO - Running pre-action listener: _resource_record_pre_action 05:09:45 INFO - Running main action method: install 05:09:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:45 INFO - Reading from file tmpfile_stdout 05:09:45 INFO - Detecting whether we're running mozinstall >=1.0... 05:09:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:09:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:09:46 INFO - Reading from file tmpfile_stdout 05:09:46 INFO - Output received: 05:09:46 INFO - Usage: mozinstall [options] installer 05:09:46 INFO - Options: 05:09:46 INFO - -h, --help show this help message and exit 05:09:46 INFO - -d DEST, --destination=DEST 05:09:46 INFO - Directory to install application into. [default: 05:09:46 INFO - "/builds/slave/test"] 05:09:46 INFO - --app=APP Application being installed. [default: firefox] 05:09:46 INFO - mkdir: /builds/slave/test/build/application 05:09:46 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'] 05:09:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:10:03 INFO - Reading from file tmpfile_stdout 05:10:03 INFO - Output received: 05:10:03 INFO - /builds/slave/test/build/application/firefox/firefox 05:10:03 INFO - Running post-action listener: _resource_record_post_action 05:10:03 INFO - [mozharness: 2017-08-08 12:10:03.914607Z] Finished install step (success) 05:10:03 INFO - [mozharness: 2017-08-08 12:10:03.914861Z] Running setup-mitmproxy step. 05:10:03 INFO - Running pre-action listener: _resource_record_pre_action 05:10:03 INFO - Running main action method: setup_mitmproxy 05:10:03 INFO - Skipping: mitmproxy is not required 05:10:03 INFO - Running post-action listener: _resource_record_post_action 05:10:03 INFO - [mozharness: 2017-08-08 12:10:03.915997Z] Finished setup-mitmproxy step (success) 05:10:03 INFO - [mozharness: 2017-08-08 12:10:03.916220Z] Running run-tests step. 05:10:03 INFO - Running pre-action listener: _resource_record_pre_action 05:10:03 INFO - Running pre-action listener: _set_gcov_prefix 05:10:03 INFO - Running main action method: run_tests 05:10:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:10:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:10:03 INFO - Python 2.7.3 05:10:03 INFO - Return code: 0 05:10:03 INFO - grabbing minidump binary from tooltool 05:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:03 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 0x247feb0>, '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 0x2480db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2481240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:10:03 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 05:10:03 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 05:10:03 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 05:10:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:10:04 INFO - Return code: 0 05:10:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:10:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:10:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:10:04 INFO - ENV: RUST_BACKTRACE is now 1 05:10:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:10:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:10:04 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/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--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 05:10:04 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/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-044 --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 05:10:04 INFO - Using env: {'DISPLAY': ':0', 05:10:04 INFO - 'HOME': '/home/cltbld', 05:10:04 INFO - 'LANG': 'en_US.UTF-8', 05:10:04 INFO - 'LANGUAGE': 'en_US:en', 05:10:04 INFO - 'LOGNAME': 'cltbld', 05:10:04 INFO - 'MAIL': '/var/mail/cltbld', 05:10:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:10:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:04 INFO - 'MOZ_NO_REMOTE': '1', 05:10:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:10:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:10:04 INFO - 'NO_EM_RESTART': '1', 05:10:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:04 INFO - 'PWD': '/builds/slave/test', 05:10:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:10:04 INFO - 'RUST_BACKTRACE': '1', 05:10:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:10:04 INFO - 'SHELL': '/bin/bash', 05:10:04 INFO - 'SHLVL': '1', 05:10:04 INFO - 'STYLO_FORCE_ENABLED': '1', 05:10:04 INFO - 'TERM': 'linux', 05:10:04 INFO - 'TMOUT': '86400', 05:10:04 INFO - 'USER': 'cltbld', 05:10:04 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471', 05:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:04 INFO - '_': '/tools/buildbot/bin/python'} 05:10:04 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/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--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 05:10:04 INFO - mozversion application_buildid: 20170808111607 05:10:04 INFO - mozversion application_changeset: 8c7788eda00e4cb4445638bdb7500009cd4cbe59 05:10:04 INFO - mozversion application_display_name: Nightly 05:10:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:10:04 INFO - mozversion application_name: Firefox 05:10:04 INFO - mozversion application_remotingname: firefox 05:10:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:10:04 INFO - mozversion application_vendor: Mozilla 05:10:04 INFO - mozversion application_version: 57.0a1 05:10:04 INFO - mozversion platform_buildid: 20170808111607 05:10:04 INFO - mozversion platform_changeset: 8c7788eda00e4cb4445638bdb7500009cd4cbe59 05:10:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:10:04 INFO - mozversion platform_version: 57.0a1 05:10:04 INFO - using testdate: 1502194204 05:10:04 INFO - actual date: 1502194204 05:10:04 INFO - starting webserver on 'localhost:44575' 05:10:04 INFO - SUITE-START | Running 2 tests 05:10:04 INFO - TEST-START | dromaeo_css 05:10:04 INFO - Initialising browser for dromaeo_css test... 05:10:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44575/getInfo.html -profile /tmp/tmpIZNtDp/profile 05:10:04 INFO - TEST-INFO | started process 13301 (/builds/slave/test/build/application/firefox/firefox http://localhost:44575/getInfo.html) 05:10:09 INFO - TEST-INFO | 13301: exit 0 05:10:10 INFO - Browser initialized. 05:10:10 INFO - Running cycle 1/1 for dromaeo_css test... 05:10:10 INFO - TEST-INFO | started process 13523 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIZNtDp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:10:11 INFO - PID 13523 | 05:10:11 INFO - PID 13523 | (/builds/slave/test/build/application/firefox/firefox:13577): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:10:11 INFO - PID 13523 | 05:10:21 INFO - PID 13523 | RSS: Main: 174120960 05:10:21 INFO - PID 13523 | 05:13:21 INFO - PID 13523 | Cycle 1(1): loaded http://localhost:44575/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44575/tests/dromaeo/cssquery-ext.html) 05:13:22 INFO - PID 13523 | RSS: Main: 176230400 05:13:22 INFO - PID 13523 | 05:16:24 INFO - PID 13523 | Cycle 1(1): loaded http://localhost:44575/tests/dromaeo/cssquery-ext.html (next: http://localhost:44575/tests/dromaeo/cssquery-jquery.html) 05:16:24 INFO - PID 13523 | RSS: Main: 173236224 05:16:24 INFO - PID 13523 | 05:19:26 INFO - PID 13523 | Cycle 1(1): loaded http://localhost:44575/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44575/tests/dromaeo/cssquery-mootools.html) 05:19:27 INFO - PID 13523 | RSS: Main: 175652864 05:19:27 INFO - PID 13523 | 05:22:29 INFO - PID 13523 | Cycle 1(1): loaded http://localhost:44575/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44575/tests/dromaeo/cssquery-prototype.html) 05:22:29 INFO - PID 13523 | RSS: Main: 174137344 05:22:29 INFO - PID 13523 | 05:25:33 INFO - PID 13523 | Cycle 1(1): loaded http://localhost:44575/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44575/tests/dromaeo/cssquery-yui.html) 05:25:34 INFO - PID 13523 | RSS: Main: 177676288 05:25:34 INFO - PID 13523 | 05:28:35 INFO - PID 13523 | Cycle 1(1): loaded http://localhost:44575/tests/dromaeo/cssquery-yui.html (next: http://localhost:44575/tests/dromaeo/cssquery-dojo.html) 05:28:36 INFO - PID 13523 | RSS: Main: 178323456 05:28:36 INFO - PID 13523 | 05:28:36 INFO - PID 13523 | __start_tp_report 05:28:36 INFO - PID 13523 | _x_x_mozilla_page_load 05:28:36 INFO - PID 13523 | _x_x_mozilla_page_load_details 05:28:36 INFO - PID 13523 | |i|pagename|runs| 05:28:36 INFO - PID 13523 | |0;dojo.html;3128.0459459864733;3180.4593219152744;3192.6966938140868;3194.233383987843;3249.463838466652;6229.439350458458;6255.499560035198;6285.151504546885;6302.495800335973;6333.873322533558;6301.779437719681;6301.810945671626;6326.968365158179;6331.525135614825;6332.3984221498995;5737.827865164052;5743.3108027036815;5746.454086861741;5764.077747560391;5871.559633027518;5749.655020698771;5777.480026797587;5787.392323806003;5872.354041055481;5872.823815285548;5611.887762244753;5655.26481557399;5663.915041274384;5782.479576838084;5850.268716410449;5740.282464691913;5744.396838331999;5757.769689212427;5781.768729250825;5786.450287222707;5680.261565996394;5711.143328500717;5740.684262365568;5807.157962095473;5807.709614519257;6174.9073763893575;6189.752409903598;6192.102145188967;6251.499880009588;6284.905726414107;6652.53432259742;6758.493113016494;6763.256041835394;6763.797086087426;6766.560173588701;6801.863962720768;6810.080639113706;6881.827954301132;6883.827904302382;6884.414824739903;6262.686865656745;6321.557490975633;6332.905006424908;6336.809895703136;6338;6930.237673855871;6995.125609298838;7007.4394048476;7008.649567521604;7032.824179395454;6584.374484423972;6615.37153970372;6681;6706.698198581075;6707.094542236736;6640.468762498988;6738.831529211809;6740.62926539045;6752.054712340277;6823.965880170567;6602.1747281589805;6640.402363787282;6686.632235227012;6690.531662783558;6692.698828552727;6668.099806526056;6678.732850686015;6684;6696.19645642526;6707.463402927754;5405.540529054996;5444.591655625764;5458.8908221835345;5465.863353416117;5500.724963751796;4613.700109492872;4636.142313671981;4673;4705.364775755298;4715;5735.741891614887;5779.3642699303045;5821.621594596338;5828.213191219216;5860.677662728591;6694.899576506356;6701;6704.497162712816;6707.865842683216;6726.630035348103;5759.481646651821;5760.193572899798;5770.740316685759;5772.365039845614;5796.681182535001;5090.669106508065;5937.435943585352;5985.22192115031;6008.549358798107;6031.276246850318;6432.388923052303;6445.129907462432;6554.016897465323;6561.179852518435;6597.670116494156;6693.531452798257;6742.123523941857;6745.291744366967;6761.729530818712;6843.794686159423;6419.293877673359;6471.514636402383;6483.805485835433;6494.9025764612625;6497.837554061186;6587.736490540325;6604.207495100619;6619.635920024445;6631.33686631333;6636.369544893228;6460.903086453613;6468.773592924225;6740.9325906740305;6803.897941530782;6919.273476285119;6541.738330466728;6555.672216389257;6646.202455705346;6662.633555154319;6666.200055993312;6561.475081993524;6563.310852360433;6633.668316584248;6652.966735166293;6655.933440665532;6413.0701048348665;6499.707513161825;6506.512011599244;6517.217933847968;6581.638009909501;6505.024246363083;6617.569857959217;6623.370779776106;6665.266820649627;6737.663116844236;6294.590851594632;6338.746450141943;6394.072859435449;6398.90401643985;6490.545661803629 05:28:36 INFO - PID 13523 | |1;ext.html;829.0797215091247;840.2353857989231;859.7635650196197;882.9072947340529;884.579824583323;41765;42589.57410425895;42634.14731705368;42722.35916461251;42997.78501107498;39476.60523394765;39712.80143599278;39720.801395993054;39725.20549589006;39831.60168398315;6884;7069.187043489987;7070;7072.257412971641;7077.71689132434;6906.447484201251;6985.231624521324;7016.017757513952;7033.964830175842;7040.542364746275;7778.5721785301785;7947.841043179133;7951.244631760002;7968.243016913384;7996.520208787462;7833.334166595818;7886.487378320391;7887.566183859885;7913.4460587758595;7913.446058775888;7575.242475752436;7654.4259180561685;7796.727114550963;7835.725749598737;7839.02012248469;38474.61525384766;38550.42174367387;38675.41986870199;38775.224495510214;38931;3377.696007359325;3430.0395889151077;3450.258194488197;3453.689167974869;3461;31663.20841978969;31730.841345793353;31791;31837.840810796028;31863.52204716931;4820.180569303227;4966.2054071348775;4976.502349765031;4976.925346119811;5009.724465154415;176995;177122;182450;183828;185257;51286;51528.48471515312;51668;51670.483295167316;51681.44955651336;53337.46662533325;53584.46415535873;53663.463365366624;53674.46325536773;53736.73131634317;16832.158392080346;16896.831031689526;17084.914575427043;17108.572285692957;17387.56531086733;14387.42450301997;14407.42370305197;14409.567712968628;14419.85580144185;14461.421543138156;14926.253687315591;14960.551183464513;14971.101733895783;15067.773983390258;15199.468018619516;5682.176084467729;5836.387179346192;5901.13794809517;5939.406059394026;5966.346249925065;949.3686698345523;971.3103696375653;972.5526257921294;991.7917237380086;995.049727510226;22192;22296.21963231279;22324.55350892973;22486.550268994855;22579.322620321418;1060.4273692206123;1069.4599227390274;1075.8870318616432;1081.1296905990596;1082.7780305037447;11684.591039313584;11691.473883675255;11706.648800535997;11751.059915206763;11774.793367830298;195155;198695;216080;219127;219432;53581;53938.19092713612;54032;54143;54212.728936355066;1469.8750606198594;1480.7482727936276;1495.2523738130717;1497.236409431198;1501.5195137556095;21974.780252197274;22020;22216.88891555532;22511.212107576157;22528.887355563118;36208.456873146395;37517.2496550062;37592.43611345887;37610;37611.2477750438;179967;181495;181710;182207.08896455434;183390;31232.843835780677;31450.21374465657;31486.685133148374;31791.205219869687;34687.653123468444;44947.550524495644;45323.54676453193;45448.31827522656;45520;46293.305600416694;33125;33144.17139571434;33356.332873342886;33394.83302583471;33937;32199;32420.513692295106;32704.34591308208;32717.18207044843;33090.83454582808;34763.65236347604;36561.26877462489;36565.634343656224;36638.63361366352;36903;31501;31993.36013279768;32155.196120097185;32259;32327.51508727324;22855.428614284778;30309.696903030686;30390;31016.224594384417;31059.689403106582 05:28:36 INFO - PID 13523 | |2;jquery.html;573.8163787587971;575.1171951055131;581.4941001328842;588.1648059755147;598.4195330529386;22465.55068898623;23379.298621041376;23495.177668781616;23563.5287294254;24811.50376992459;25942;26537.469250615024;26609.201723948263;26637.733622663814;26747.19758407251;6428.550001499897;6539.803805885831;6585.407313341799;6593.109930159415;6610.272869984322;5970.522358211333;6441.935580644204;6461;6515.869682606369;6543.9672801635925;6420.133282006939;6464;6495.415412612864;6495.5777874438245;6509.414152726254;6514.869702605945;6520.576162549419;6522.478201743849;6527.314631963647;6527.706253218569;6039.094135879654;6055.909161362583;6056.5457590680435;6061.636301821904;6062.696865156725;29029.12912612625;29491;30132.397352053056;30748.53877191844;31083.067507974796;1960.4412742368356;2001.039501039509;2001.949951251216;2002.0390212697841;2002.1290738528642;19412.514687132792;19955.800441995685;20045.1981920723;20106.195752169897;20119.39641810748;2366.562185995579;2425.927222183337;2427.1747605814107;2438.1344622659053;2540.174443305933;129945.35027324897;133702;133730;133926.33036834752;134285;14308;14916.328765205591;15009.774853377208;15032.473863414729;16855.325786968384;23806.523869522513;23878;23893.880530597584;23948.161814336414;23965.640515392282;10047.397156170653;10088.344257623232;10217.948910255549;10222.6422075227;10428.478576071166;8074.636641351153;9411.811763764823;9541.666041688508;9552.513130135323;9710.465924374086;7989.880151797728;9063.501507417155;9396.812063758687;9480.478573678376;9498.810023799486;2861.227468583471;2863.110347170899;2868.842213678226;2889.4076714273524;2955.1873234860154;923.8244733500613;968.4334664221509;978.9853152202722;984.3503287829998;988.367444835306;19279.325223617107;19414;19638.70541941872;19771.209151633775;19803.603927921362;1014.8934361891895;1055.377327376836;1058.8305871060593;1062.516554967488;1065.9253852230424;10717.732056698645;10833.29583577065;10882.183836212316;10971.341719496855;11198.888011119947;529366;535194;539283;542954;547506;120181.39909300572;121088.39455802664;121673.39163304126;122347;123298;1252.1485389934933;1280.801475771257;1321.378951892609;1325.5956933135487;1326.4031185966353;20133.6979945298;20471;20655.690164647243;21007.159713611894;21914.342569722932;59273;59518.70240648942;59766;60668.69665651643;62296;68615;68896.31103688899;69183.30816691769;69272.30727692659;69283.65358173176;14917.47788827386;15272.465463708717;15377.538673839803;15380.077195368314;15431;24120.638190427508;24165.395865103514;24326.51346973086;24340.269791905564;24616.876915615307;18894.433167005012;19102.33141840029;19313.22747090157;19352.903235483733;19413.4175974721;16389.672206555562;16786.916065419595;17508;17738.29046838112;17785.377511787025;19114;19664.508387290432;19715;19749.506262343555;19800.405987820388;15025.173615450818;15110.471133510275;15118.244087795787;15129.697406052037;15274.847251527342;18713.15790789445;18833;18977.90511047436;19205.903970480616;19280.32518861833 05:28:36 INFO - PID 13523 | |3;mootools.html;1354.8306461692287;1369.8767110960011;1383.8477767445581;1408.0073548148557;1413.3145424469124;13674.726505469896;14017.50938717145;14226.075305105162;14251.64370890727;14302.713945721105;14561.199134047623;14841.406343746237;14850.777238341434;14854.628634284149;14877;2680.249530131561;2752.8898844046316;2753.5731961545994;2753.6282601848793;2754.793390495711;2478.603423452248;2567.255495906185;2623.842569445839;2630.289821748127;2637.235201791478;2743.8353698778037;2762.9309267268377;2764.060219525361;2765.4883846488465;2768.1142034548952;2691.905783297585;2705.837649741022;2721.9047333343237;2755.9035433759723;2763.585462180669;2610.778083862874;2631.0922731657547;2651.814372993891;2664.0276299150864;2683.449892771977;14008.299585020708;14042.508512202025;14093.436262549485;14103.929480352635;14119.717605647933;7428.665710043061;7809.531428114275;7822.530648161129;7833.060032796101;7838.686452541949;5043.445221025684;5200.401953775288;5279.340082489689;5355.6251062425645;5365.087935051011;1731.913404329791;1749.5101371615967;1767.1164417791103;1792.0143920843484;1796.5329014456336;22611.208607698816;22870;22876.19933302343;22887.313380598607;22912.8232817882;6117.1130186123655;6307.2431308242385;6323.27282362525;6477.514186436036;6539.313372095957;15949.760753588473;15991.68016639684;15995.920020400057;16005.919970400073;16011.039337639779;7300.196978332379;7613;7614.086309642878;7617.771466856003;7627.923720762832;7028.332308430691;7063.646817659096;7068.081149450539;7200.063991681048;7246.384057355184;5162.819301324511;5271.551918087006;5499.945000549943;5635.3237611486065;5672.829815105553;1740.6083631182883;1778.315348590802;1781.6080462298273;1783.7413575031808;1829.2957211473679;868.2533021601417;902.3322741171617;905.0677801864091;911.7355966769695;913.1644545241163;7714.729984450517;8297.12880147588;8347.582620868932;8350.958245208734;8353.707620233263;959.6353385713384;965.9082387173207;976.5654283843763;980.8332583460829;999.7200783780553;4282.035414129717;4423.159599676052;5284.735763211901;5309.177077552986;5361.571074314045;18948.14733337003;19713.507162321057;19756.506087347643;19765.01174941247;19775.505612359808;19067.904660476885;19301.80698193028;19590.314338998065;19623.705644415346;19709.40871773849;10364.429956352473;10537.525811338508;10669.093127084285;10682.305650132717;10704.983026612459;8026.317762990211;8239.382046346667;8414.537200454033;8498.447600905922;8809.251213646656;16412.953086641206;16562.50312490627;16686.49940501787;16859.662806744043;17061.232244548777;16615.08617026075;16658;16706.677380554254;16738;16874.578135546708;11090.112790976542;11151.72120696989;11207.551697931991;11355.37545435009;11370.488328025092;11812.40937953116;12103.33431661267;12571.685707857378;12740.490380384681;12914.612561623166;10303.742406439598;10376.844347334645;10454.947725261325;10515.158787296845;10877.945610271898;10485.52815123306;10534.52594633228;10582.365058096539;10833.783324333626;10882.61910833117;9359.953200233955;9609.231261499202;9623.326367154232;9624.711258662252;9625.71122866315;7225.24134965821;7269.672864721206;7272.200057993722;7274.599897005715;7325.560466372035;7094.822629434099;7106.786796396116;7130.786076417716;7132.251113632994;7214.531055481587 05:28:36 INFO - PID 13523 | |4;prototype.html;495.6234853875666;504.8409750928457;526.176533724721;526.1949217696924;528.1259515501844;6241.188645476082;6305.653189074605;6335.398137176967;6341.365863413656;6398.392152745488;5869.943410186164;6115.938840611592;6148.3851614838495;6160.630362178261;6162.691865406734;4901.362822833027;4951;4968.602511799066;4984.4018717753925;4997.175466048097;4966.453690094087;5007.574356179729;5023.598112151038;5043.84868453947;5063.341765570489;4716.858494245178;4848.830290939818;4946.2655694950445;4969.105560999019;4986.476419975905;4896.902061958741;4914.11545921734;4925.507449255082;4961.5286547777905;4964.801407943696;4412.955870441287;4445.73325600465;4452.777361131963;4485.102979404132;4514.848713578017;6087.625825927892;6091.51267898567;6123.050927106306;6124.203853499062;6155.353687862755;5745.913811292834;5751.223584816038;5777.104548794901;5782.219400380936;5801.448862358111;6468.644224567647;6509.153810004716;6548.70530826114;6629.50836061887;6636.502262330345;4878.804847806084;4879.292502587141;4889.144399730033;4891.804327826882;4921.360223170965;6865.5537390069985;6873.725050997954;6910.827229319257;6916.930830691729;6991.265917078634;5029.698218106925;5031.169856973558;5054.620903432257;5057.367829021372;5066.909185396905;5047.141985862412;5095.97452012745;5133.204353325241;5144.485551444825;5146.768395422215;5718.7426565804635;5722.685252311079;5731.627444216113;5738.885222295614;5773.538116950718;6571.868562628816;6606.768763093269;6613.14029176204;6659.700313485905;6742.662866856637;1672.6152984813561;1718.6734520441075;1760.5246583422404;1779.4483710049926;1786.857051435882;1173.724174818917;1220.6267948557872;1232.7904256276274;1243.098753403775;1243.247835059794;5741.540676745851;5758.337791153987;5768.884622307531;5819.679917604488;5822.359540450547;6550;6655.5341126121675;6685.465162786965;6703.011708033712;6703.223645733884;5798;5804.912926306109;5851.531877449794;5866.7653293868625;5884.235049443545;3575.982120089435;3613.2050948791225;3650.5254316938626;3656.597774244831;3679.5584529856055;6519.119918810995;6523.18460192476;6587.538872278895;6612.636305003271;6637.838378283781;6926.445884329241;6970.198427180838;6998.545094568837;7038.120234970629;7059.2940705928995;6468.611883287018;6505.73977040932;6526.641034743135;6530.542861999615;6563.671816409161;6750.464619012363;6752.987051942248;6771.695273712793;6823.658817059227;6866.313368663094;6720.294369091175;6744.460443164633;6772.424343930821;6786.423154031962;6799.218089919723;6460.353964603502;6462.547621666627;6467.385598368148;6490.935090649222;6540.607563546202;4993.525615066563;5006.499350064964;5050.469700681522;5070.036693028313;5081.695098293966;6742.561733487308;6746.628935408403;6792.93207067923;6805.251422343637;6887.862242755016;6499.740010399721;6510.056041873996;6586.835329116619;6605.702743376656;6613.900791488035;6502.869942601027;6612.305707900794;6617.139771829631;6636.9004464932095;6656.966715166393;6084.239470066242;6269.3417191196095;6341.873162536631;6409.262934762562;6489.5781774184525;6445.967770161306;6509.772157974448;6520.478361730955;6568.540202185801;6605.438537724347;6302.369763023661;6370.74517019314;6401.807945761635;6403.487720982217;6411.351769966736 05:28:36 INFO - PID 13523 | |5;yui.html;878.7583414560995;879.5602198900546;905.6875377994589;910.2854259406367;913.9634414623415;18213.544661383454;19122.904385478054;19134.330298439556;19145.52136196596;19167.32914347998;15868.682626347467;16339.26473308701;17538.12309384529;20509.897450512763;20550.69173962392;1034.1571619130405;1053.3837704942619;1060.76132870104;1062.4156713807413;1064.8083344997897;975.770693886936;1012.0941757127404;1057.5241141486363;1063.9521221545049;1064.8509208710786;1098.4507746126937;1116.0848104554268;1116.8380584815197;1118.9216754827166;1119.5969450997634;1101.0365929811414;1115.1524841120724;1115.2472081345059;1118.1613789657756;1120.8150655141892;812.5490352854164;813.8372325534916;815.9714409995681;816.0370762500247;816.6529225079317;17095.572610684707;17227.741583876254;19279.22883084675;19447.513812154666;19480.318188863464;21589.892050539645;21790.891045544828;21829.563408731738;22202.55594888093;22215.777842221694;16661.416850410176;16745.91627041857;16988.74516882248;17028.574285642833;17102.914485427616;3540.3627347077518;3618.6743193112484;3634.727395445352;3634.890953271406;3639.1993761372455;125071;125814.37092814477;129589.35205324102;131212;131693;16587.087710176056;16667;16944.915275423544;16980.320787168373;17040;28681.13956581306;29763.85118074439;30896.69103308938;30963.84518077395;30974.53538196929;8649.351298652626;8798.428102173339;8804.339674524435;8838.204561589488;8874.911250887537;3865.2269546090893;3940.408938659224;4011.799410029487;4033.596640335943;4035.9596404035583;5228.477152284741;5399.865003374947;5409.26974858397;5447.210154527571;5456.7544460499375;1050.6585359757955;1079.5250089960393;1094.1738987064716;1101.7576133250673;1109.8612673415823;675.4191395399952;690.3131384272681;693.5803838677629;704.968276427562;708.7342246657504;12219.572314968933;12587.685307867376;12622.242665440102;12685.112042156961;12708.682282943;687.2714922182503;688.1192074145102;694.6387878303254;697.870893884633;699.9510034297653;5591.245181900392;5668.178114173505;6089.817305480843;6195.411435913581;6327.841803954938;99199.50400247953;99299;102221.48889255506;103703;104165;90648;90894.09105908856;92474.53762731144;92658.53670731603;93317.53341233387;76852.23147768562;78937.21062789299;80736.59631701803;82065;86932.13067869366;12214;12353;12536.686582835502;12724.936375318435;12816.935915320364;34435;34969;35634;36007.45988810118;37358;46927.53072469232;65185;67718;68784;71942.64028679823;18692.158852851382;18810.52973675669;19209;19293.517662058563;19444.611107778048;18823.811761882753;18867.90566047161;20655.483612909797;20979.265725699526;21526;18674;19212.8078719211;19243.13405896766;19320.903395482932;19565.608687826447;18660;18869.339573114874;19090.04549772478;19180.13689383953;19381;17906.731399028766;18256.817431826043;18480;18497.72253416173;18525.90737046306;13644.931775341456;13772.655683607589;14050.578482645536;14235.430582776988;14289.356978935764;14323.355449005028;14619;14660.340284687429;14684.706305874037;14688.09659460052 05:28:36 INFO - PID 13523 | __end_tp_report 05:28:36 INFO - PID 13523 | __start_cc_report 05:28:36 INFO - PID 13523 | _x_x_mozilla_cycle_collect,374 05:28:36 INFO - PID 13523 | __end_cc_report 05:28:36 INFO - PID 13523 | __startTimestamp1502195316205__endTimestamp 05:28:36 INFO - PID 13523 | 05:28:36 INFO - PID 13523 | ------- Summary: start ------- 05:28:36 INFO - PID 13523 | Number of tests: 6 05:28:36 INFO - PID 13523 | 05:28:36 INFO - PID 13523 | [#0] dojo.html Cycles:165 Average:6196.28 Median:6445.13 stddev:736.39 (11.4%) stddev-sans-first:698.21 05:28:36 INFO - PID 13523 | Values: 3128.0 3180.5 3192.7 3194.2 3249.5 6229.4 6255.5 6285.2 6302.5 6333.9 6301.8 6301.8 6327.0 6331.5 6332.4 5737.8 5743.3 5746.5 5764.1 5871.6 5749.7 5777.5 5787.4 5872.4 5872.8 5611.9 5655.3 5663.9 5782.5 5850.3 5740.3 5744.4 5757.8 5781.8 5786.5 5680.3 5711.1 5740.7 5807.2 5807.7 6174.9 6189.8 6192.1 6251.5 6284.9 6652.5 6758.5 6763.3 6763.8 6766.6 6801.9 6810.1 6881.8 6883.8 6884.4 6262.7 6321.6 6332.9 6336.8 6338.0 6930.2 6995.1 7007.4 7008.6 7032.8 6584.4 6615.4 6681.0 6706.7 6707.1 6640.5 6738.8 6740.6 6752.1 6824.0 6602.2 6640.4 6686.6 6690.5 6692.7 6668.1 6678.7 6684.0 6696.2 6707.5 5405.5 5444.6 5458.9 5465.9 5500.7 4613.7 4636.1 4673.0 4705.4 4715.0 5735.7 5779.4 5821.6 5828.2 5860.7 6694.9 6701.0 6704.5 6707.9 6726.6 5759.5 5760.2 5770.7 5772.4 5796.7 5090.7 5937.4 5985.2 6008.5 6031.3 6432.4 6445.1 6554.0 6561.2 6597.7 6693.5 6742.1 6745.3 6761.7 6843.8 6419.3 6471.5 6483.8 6494.9 6497.8 6587.7 6604.2 6619.6 6631.3 6636.4 6460.9 6468.8 6740.9 6803.9 6919.3 6541.7 6555.7 6646.2 6662.6 6666.2 6561.5 6563.3 6633.7 6653.0 6655.9 6413.1 6499.7 6506.5 6517.2 6581.6 6505.0 6617.6 6623.4 6665.3 6737.7 6294.6 6338.7 6394.1 6398.9 6490.5 05:28:36 INFO - PID 13523 | 05:28:36 INFO - PID 13523 | [#1] ext.html Cycles:180 Average:36720.32 Median:35486.05 stddev:49532.17 (139.6%) stddev-sans-first:49597.80 05:28:36 INFO - PID 13523 | Values: 829.1 840.2 859.8 882.9 884.6 41765.0 42589.6 42634.1 42722.4 42997.8 39476.6 39712.8 39720.8 39725.2 39831.6 6884.0 7069.2 7070.0 7072.3 7077.7 6906.4 6985.2 7016.0 7034.0 7040.5 7778.6 7947.8 7951.2 7968.2 7996.5 7833.3 7886.5 7887.6 7913.4 7913.4 7575.2 7654.4 7796.7 7835.7 7839.0 38474.6 38550.4 38675.4 38775.2 38931.0 3377.7 3430.0 3450.3 3453.7 3461.0 31663.2 31730.8 31791.0 31837.8 31863.5 4820.2 4966.2 4976.5 4976.9 5009.7 176995.0 177122.0 182450.0 183828.0 185257.0 51286.0 51528.5 51668.0 51670.5 51681.4 53337.5 53584.5 53663.5 53674.5 53736.7 16832.2 16896.8 17084.9 17108.6 17387.6 14387.4 14407.4 14409.6 14419.9 14461.4 14926.3 14960.6 14971.1 15067.8 15199.5 5682.2 5836.4 5901.1 5939.4 5966.3 949.4 971.3 972.6 991.8 995.0 22192.0 22296.2 22324.6 22486.6 22579.3 1060.4 1069.5 1075.9 1081.1 1082.8 11684.6 11691.5 11706.6 11751.1 11774.8 195155.0 198695.0 216080.0 219127.0 219432.0 53581.0 53938.2 54032.0 54143.0 54212.7 1469.9 1480.7 1495.3 1497.2 1501.5 21974.8 22020.0 22216.9 22511.2 22528.9 36208.5 37517.2 37592.4 37610.0 37611.2 179967.0 181495.0 181710.0 182207.1 183390.0 31232.8 31450.2 31486.7 31791.2 34687.7 44947.6 45323.5 45448.3 45520.0 46293.3 33125.0 33144.2 33356.3 33394.8 33937.0 32199.0 32420.5 32704.3 32717.2 33090.8 34763.7 36561.3 36565.6 36638.6 36903.0 31501.0 31993.4 32155.2 32259.0 32327.5 22855.4 30309.7 30390.0 31016.2 31059.7 05:28:36 INFO - PID 13523 | 05:28:36 INFO - PID 13523 | [#2] jquery.html Cycles:180 Average:36531.30 Median:23842.26 stddev:90092.96 (377.9%) stddev-sans-first:90305.24 05:28:36 INFO - PID 13523 | Values: 573.8 575.1 581.5 588.2 598.4 22465.6 23379.3 23495.2 23563.5 24811.5 25942.0 26537.5 26609.2 26637.7 26747.2 6428.6 6539.8 6585.4 6593.1 6610.3 5970.5 6441.9 6461.0 6515.9 6544.0 6420.1 6464.0 6495.4 6495.6 6509.4 6514.9 6520.6 6522.5 6527.3 6527.7 6039.1 6055.9 6056.5 6061.6 6062.7 29029.1 29491.0 30132.4 30748.5 31083.1 1960.4 2001.0 2001.9 2002.0 2002.1 19412.5 19955.8 20045.2 20106.2 20119.4 2366.6 2425.9 2427.2 2438.1 2540.2 129945.4 133702.0 133730.0 133926.3 134285.0 14308.0 14916.3 15009.8 15032.5 16855.3 23806.5 23878.0 23893.9 23948.2 23965.6 10047.4 10088.3 10217.9 10222.6 10428.5 8074.6 9411.8 9541.7 9552.5 9710.5 7989.9 9063.5 9396.8 9480.5 9498.8 2861.2 2863.1 2868.8 2889.4 2955.2 923.8 968.4 979.0 984.4 988.4 19279.3 19414.0 19638.7 19771.2 19803.6 1014.9 1055.4 1058.8 1062.5 1065.9 10717.7 10833.3 10882.2 10971.3 11198.9 529366.0 535194.0 539283.0 542954.0 547506.0 120181.4 121088.4 121673.4 122347.0 123298.0 1252.1 1280.8 1321.4 1325.6 1326.4 20133.7 20471.0 20655.7 21007.2 21914.3 59273.0 59518.7 59766.0 60668.7 62296.0 68615.0 68896.3 69183.3 69272.3 69283.7 14917.5 15272.5 15377.5 15380.1 15431.0 24120.6 24165.4 24326.5 24340.3 24616.9 18894.4 19102.3 19313.2 19352.9 19413.4 16389.7 16786.9 17508.0 17738.3 17785.4 19114.0 19664.5 19715.0 19749.5 19800.4 15025.2 15110.5 15118.2 15129.7 15274.8 18713.2 18833.0 18977.9 19205.9 19280.3 05:28:36 INFO - PID 13523 | 05:28:36 INFO - PID 13523 | [#3] mootools.html Cycles:180 Average:8749.08 Median:2634.16 stddev:5899.00 (223.9%) stddev-sans-first:5889.38 05:28:36 INFO - PID 13523 | Values: 1354.8 1369.9 1383.8 1408.0 1413.3 13674.7 14017.5 14226.1 14251.6 14302.7 14561.2 14841.4 14850.8 14854.6 14877.0 2680.2 2752.9 2753.6 2753.6 2754.8 2478.6 2567.3 2623.8 2630.3 2637.2 2743.8 2762.9 2764.1 2765.5 2768.1 2691.9 2705.8 2721.9 2755.9 2763.6 2610.8 2631.1 2651.8 2664.0 2683.4 14008.3 14042.5 14093.4 14103.9 14119.7 7428.7 7809.5 7822.5 7833.1 7838.7 5043.4 5200.4 5279.3 5355.6 5365.1 1731.9 1749.5 1767.1 1792.0 1796.5 22611.2 22870.0 22876.2 22887.3 22912.8 6117.1 6307.2 6323.3 6477.5 6539.3 15949.8 15991.7 15995.9 16005.9 16011.0 7300.2 7613.0 7614.1 7617.8 7627.9 7028.3 7063.6 7068.1 7200.1 7246.4 5162.8 5271.6 5499.9 5635.3 5672.8 1740.6 1778.3 1781.6 1783.7 1829.3 868.3 902.3 905.1 911.7 913.2 7714.7 8297.1 8347.6 8351.0 8353.7 959.6 965.9 976.6 980.8 999.7 4282.0 4423.2 5284.7 5309.2 5361.6 18948.1 19713.5 19756.5 19765.0 19775.5 19067.9 19301.8 19590.3 19623.7 19709.4 10364.4 10537.5 10669.1 10682.3 10705.0 8026.3 8239.4 8414.5 8498.4 8809.3 16413.0 16562.5 16686.5 16859.7 17061.2 16615.1 16658.0 16706.7 16738.0 16874.6 11090.1 11151.7 11207.6 11355.4 11370.5 11812.4 12103.3 12571.7 12740.5 12914.6 10303.7 10376.8 10454.9 10515.2 10877.9 10485.5 10534.5 10582.4 10833.8 10882.6 9360.0 9609.2 9623.3 9624.7 9625.7 7225.2 7269.7 7272.2 7274.6 7325.6 7094.8 7106.8 7130.8 7132.3 7214.5 05:28:36 INFO - PID 13523 | 05:28:36 INFO - PID 13523 | [#4] prototype.html Cycles:180 Average:5534.45 Median:6123.63 stddev:1556.19 (25.4%) stddev-sans-first:1513.90 05:28:36 INFO - PID 13523 | Values: 495.6 504.8 526.2 526.2 528.1 6241.2 6305.7 6335.4 6341.4 6398.4 5869.9 6115.9 6148.4 6160.6 6162.7 4901.4 4951.0 4968.6 4984.4 4997.2 4966.5 5007.6 5023.6 5043.8 5063.3 4716.9 4848.8 4946.3 4969.1 4986.5 4896.9 4914.1 4925.5 4961.5 4964.8 4413.0 4445.7 4452.8 4485.1 4514.8 6087.6 6091.5 6123.1 6124.2 6155.4 5745.9 5751.2 5777.1 5782.2 5801.4 6468.6 6509.2 6548.7 6629.5 6636.5 4878.8 4879.3 4889.1 4891.8 4921.4 6865.6 6873.7 6910.8 6916.9 6991.3 5029.7 5031.2 5054.6 5057.4 5066.9 5047.1 5096.0 5133.2 5144.5 5146.8 5718.7 5722.7 5731.6 5738.9 5773.5 6571.9 6606.8 6613.1 6659.7 6742.7 1672.6 1718.7 1760.5 1779.4 1786.9 1173.7 1220.6 1232.8 1243.1 1243.2 5741.5 5758.3 5768.9 5819.7 5822.4 6550.0 6655.5 6685.5 6703.0 6703.2 5798.0 5804.9 5851.5 5866.8 5884.2 3576.0 3613.2 3650.5 3656.6 3679.6 6519.1 6523.2 6587.5 6612.6 6637.8 6926.4 6970.2 6998.5 7038.1 7059.3 6468.6 6505.7 6526.6 6530.5 6563.7 6750.5 6753.0 6771.7 6823.7 6866.3 6720.3 6744.5 6772.4 6786.4 6799.2 6460.4 6462.5 6467.4 6490.9 6540.6 4993.5 5006.5 5050.5 5070.0 5081.7 6742.6 6746.6 6792.9 6805.3 6887.9 6499.7 6510.1 6586.8 6605.7 6613.9 6502.9 6612.3 6617.1 6636.9 6657.0 6084.2 6269.3 6341.9 6409.3 6489.6 6446.0 6509.8 6520.5 6568.5 6605.4 6302.4 6370.7 6401.8 6403.5 6411.4 05:28:36 INFO - PID 13523 | 05:28:36 INFO - PID 13523 | [#5] yui.html Cycles:180 Average:23072.71 Median:19307.21 stddev:30824.63 (159.7%) stddev-sans-first:30866.05 05:28:36 INFO - PID 13523 | Values: 878.8 879.6 905.7 910.3 914.0 18213.5 19122.9 19134.3 19145.5 19167.3 15868.7 16339.3 17538.1 20509.9 20550.7 1034.2 1053.4 1060.8 1062.4 1064.8 975.8 1012.1 1057.5 1064.0 1064.9 1098.5 1116.1 1116.8 1118.9 1119.6 1101.0 1115.2 1115.2 1118.2 1120.8 812.5 813.8 816.0 816.0 816.7 17095.6 17227.7 19279.2 19447.5 19480.3 21589.9 21790.9 21829.6 22202.6 22215.8 16661.4 16745.9 16988.7 17028.6 17102.9 3540.4 3618.7 3634.7 3634.9 3639.2 125071.0 125814.4 129589.4 131212.0 131693.0 16587.1 16667.0 16944.9 16980.3 17040.0 28681.1 29763.9 30896.7 30963.8 30974.5 8649.4 8798.4 8804.3 8838.2 8874.9 3865.2 3940.4 4011.8 4033.6 4036.0 5228.5 5399.9 5409.3 5447.2 5456.8 1050.7 1079.5 1094.2 1101.8 1109.9 675.4 690.3 693.6 705.0 708.7 12219.6 12587.7 12622.2 12685.1 12708.7 687.3 688.1 694.6 697.9 700.0 5591.2 5668.2 6089.8 6195.4 6327.8 99199.5 99299.0 102221.5 103703.0 104165.0 90648.0 90894.1 92474.5 92658.5 93317.5 76852.2 78937.2 80736.6 82065.0 86932.1 12214.0 12353.0 12536.7 12724.9 12816.9 34435.0 34969.0 35634.0 36007.5 37358.0 46927.5 65185.0 67718.0 68784.0 71942.6 18692.2 18810.5 19209.0 19293.5 19444.6 18823.8 18867.9 20655.5 20979.3 21526.0 18674.0 19212.8 19243.1 19320.9 19565.6 18660.0 18869.3 19090.0 19180.1 19381.0 17906.7 18256.8 18480.0 18497.7 18525.9 13644.9 13772.7 14050.6 14235.4 14289.4 14323.4 14619.0 14660.3 14684.7 14688.1 05:28:36 INFO - PID 13523 | -------- Summary: end -------- 05:28:36 INFO - PID 13523 | 05:28:36 INFO - TEST-INFO | 13523: exit 0 05:28:36 INFO - TEST-OK | dromaeo_css | took 1112596ms 05:28:36 INFO - TEST-START | kraken 05:28:36 INFO - Initialising browser for kraken test... 05:28:36 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44575/getInfo.html -profile /tmp/tmpzcZHWi/profile 05:28:36 INFO - TEST-INFO | started process 13899 (/builds/slave/test/build/application/firefox/firefox http://localhost:44575/getInfo.html) 05:28:42 INFO - TEST-INFO | 13899: exit 0 05:28:42 INFO - Browser initialized. 05:28:42 INFO - Running cycle 1/1 for kraken test... 05:28:42 INFO - TEST-INFO | started process 14127 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzcZHWi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:28:43 INFO - PID 14127 | 05:28:43 INFO - PID 14127 | (/builds/slave/test/build/application/firefox/firefox:14181): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:28:43 INFO - PID 14127 | 05:28:53 INFO - PID 14127 | RSS: Main: 171671552 05:28:53 INFO - PID 14127 | 05:28:56 INFO - PID 14127 | Cycle 1(1): loaded http://localhost:44575/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44575/tests/kraken/driver.html?testName=audio-beat-detection) 05:28:57 INFO - PID 14127 | RSS: Main: 178282496 05:28:57 INFO - PID 14127 | 05:29:00 INFO - PID 14127 | Cycle 1(1): loaded http://localhost:44575/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44575/tests/kraken/driver.html?testName=audio-dft) 05:29:00 INFO - PID 14127 | RSS: Main: 178200576 05:29:00 INFO - PID 14127 | 05:29:03 INFO - PID 14127 | Cycle 1(1): loaded http://localhost:44575/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44575/tests/kraken/driver.html?testName=audio-fft) 05:29:04 INFO - PID 14127 | RSS: Main: 173535232 05:29:04 INFO - PID 14127 | 05:29:06 INFO - PID 14127 | Cycle 1(1): loaded http://localhost:44575/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44575/tests/kraken/driver.html?testName=audio-oscillator) 05:29:07 INFO - PID 14127 | RSS: Main: 173862912 05:29:07 INFO - PID 14127 | 05:29:09 INFO - PID 14127 | Cycle 1(1): loaded http://localhost:44575/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44575/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:29:10 INFO - PID 14127 | RSS: Main: 174194688 05:29:10 INFO - PID 14127 | 05:29:13 INFO - PID 14127 | Cycle 1(1): loaded http://localhost:44575/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44575/tests/kraken/driver.html?testName=imaging-darkroom) 05:29:13 INFO - PID 14127 | RSS: Main: 176967680 05:29:13 INFO - PID 14127 | 05:29:17 INFO - PID 14127 | Cycle 1(1): loaded http://localhost:44575/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44575/tests/kraken/driver.html?testName=imaging-desaturate) 05:29:18 INFO - PID 14127 | RSS: Main: 176291840 05:29:18 INFO - PID 14127 | 05:29:21 INFO - PID 14127 | Cycle 1(1): loaded http://localhost:44575/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44575/tests/kraken/driver.html?testName=json-parse-financial) 05:29:21 INFO - PID 14127 | RSS: Main: 177442816 05:29:21 INFO - PID 14127 | 05:29:23 INFO - PID 14127 | Cycle 1(1): loaded http://localhost:44575/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44575/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:29:23 INFO - PID 14127 | RSS: Main: 177201152 05:29:23 INFO - PID 14127 | 05:29:25 INFO - PID 14127 | Cycle 1(1): loaded http://localhost:44575/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44575/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:29:26 INFO - PID 14127 | RSS: Main: 174145536 05:29:26 INFO - PID 14127 | 05:29:28 INFO - PID 14127 | Cycle 1(1): loaded http://localhost:44575/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44575/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:29:29 INFO - PID 14127 | RSS: Main: 174178304 05:29:29 INFO - PID 14127 | 05:29:32 INFO - PID 14127 | Cycle 1(1): loaded http://localhost:44575/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44575/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:29:32 INFO - PID 14127 | RSS: Main: 175042560 05:29:32 INFO - PID 14127 | 05:29:35 INFO - PID 14127 | Cycle 1(1): loaded http://localhost:44575/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44575/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:29:36 INFO - PID 14127 | RSS: Main: 174997504 05:29:36 INFO - PID 14127 | 05:29:38 INFO - PID 14127 | Cycle 1(1): loaded http://localhost:44575/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44575/tests/kraken/driver.html?testName=ai-astar) 05:29:38 INFO - PID 14127 | RSS: Main: 176615424 05:29:38 INFO - PID 14127 | 05:29:38 INFO - PID 14127 | __start_tp_report 05:29:38 INFO - PID 14127 | _x_x_mozilla_page_load 05:29:38 INFO - PID 14127 | _x_x_mozilla_page_load_details 05:29:38 INFO - PID 14127 | |i|pagename|runs| 05:29:38 INFO - PID 14127 | |0;ai-astar;99;115;103;108;109;109;115;100;108;108 05:29:38 INFO - PID 14127 | |1;audio-beat-detection;145;125;146;191;121;149;154;133;176;129 05:29:38 INFO - PID 14127 | |2;audio-dft;171;163;171;165;163;165;167;174;169;168 05:29:38 INFO - PID 14127 | |3;audio-fft;74;88;74;183;75;81;75;75;169;75 05:29:38 INFO - PID 14127 | |4;audio-oscillator;85;80;139;79;79;79;130;79;81;130 05:29:38 INFO - PID 14127 | |5;imaging-gaussian-blur;117;116;113;114;114;114;114;117;115;115 05:29:38 INFO - PID 14127 | |6;imaging-darkroom;150;150;152;150;150;152;150;152;152;151 05:29:38 INFO - PID 14127 | |7;imaging-desaturate;94;104;94;98;94;94;96;94;96;95 05:29:38 INFO - PID 14127 | |8;json-parse-financial;53;53;53;54;53;53;58;54;52;54 05:29:38 INFO - PID 14127 | |9;json-stringify-tinderbox;55;59;55;54;54;54;54;54;54;55 05:29:38 INFO - PID 14127 | |10;stanford-crypto-aes;81;84;81;88;82;87;80;84;87;88 05:29:38 INFO - PID 14127 | |11;stanford-crypto-ccm;151;131;143;128;125;141;126;140;125;167 05:29:38 INFO - PID 14127 | |12;stanford-crypto-pbkdf2;176;182;177;176;184;174;179;175;181;173 05:29:38 INFO - PID 14127 | |13;stanford-crypto-sha256-iterative;68;65;71;67;73;66;66;66;70;66 05:29:38 INFO - PID 14127 | __end_tp_report 05:29:38 INFO - PID 14127 | __start_cc_report 05:29:38 INFO - PID 14127 | _x_x_mozilla_cycle_collect,975 05:29:38 INFO - PID 14127 | __end_cc_report 05:29:38 INFO - PID 14127 | __startTimestamp1502195378668__endTimestamp 05:29:38 INFO - PID 14127 | 05:29:38 INFO - PID 14127 | ------- Summary: start ------- 05:29:38 INFO - PID 14127 | Number of tests: 14 05:29:38 INFO - PID 14127 | 05:29:38 INFO - PID 14127 | [#0] ai-astar Cycles:10 Average:107.40 Median:109.00 stddev:5.44 (5.0%) stddev-sans-first:4.85 05:29:38 INFO - PID 14127 | Values: 99.0 115.0 103.0 108.0 109.0 109.0 115.0 100.0 108.0 108.0 05:29:38 INFO - PID 14127 | 05:29:38 INFO - PID 14127 | [#1] audio-beat-detection Cycles:10 Average:146.90 Median:147.50 stddev:22.40 (15.2%) stddev-sans-first:23.75 05:29:38 INFO - PID 14127 | Values: 145.0 125.0 146.0 191.0 121.0 149.0 154.0 133.0 176.0 129.0 05:29:38 INFO - PID 14127 | 05:29:38 INFO - PID 14127 | [#2] audio-dft Cycles:10 Average:167.60 Median:168.50 stddev:3.69 (2.2%) stddev-sans-first:3.70 05:29:38 INFO - PID 14127 | Values: 171.0 163.0 171.0 165.0 163.0 165.0 167.0 174.0 169.0 168.0 05:29:38 INFO - PID 14127 | 05:29:38 INFO - PID 14127 | [#3] audio-fft Cycles:10 Average:96.90 Median:75.00 stddev:42.05 (56.1%) stddev-sans-first:43.77 05:29:38 INFO - PID 14127 | Values: 74.0 88.0 74.0 183.0 75.0 81.0 75.0 75.0 169.0 75.0 05:29:38 INFO - PID 14127 | 05:29:38 INFO - PID 14127 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:79.00 stddev:25.64 (32.5%) stddev-sans-first:26.88 05:29:38 INFO - PID 14127 | Values: 85.0 80.0 139.0 79.0 79.0 79.0 130.0 79.0 81.0 130.0 05:29:38 INFO - PID 14127 | 05:29:38 INFO - PID 14127 | [#5] imaging-gaussian-blur Cycles:10 Average:114.90 Median:115.00 stddev:1.37 (1.2%) stddev-sans-first:1.22 05:29:38 INFO - PID 14127 | Values: 117.0 116.0 113.0 114.0 114.0 114.0 114.0 117.0 115.0 115.0 05:29:38 INFO - PID 14127 | 05:29:38 INFO - PID 14127 | [#6] imaging-darkroom Cycles:10 Average:150.90 Median:151.50 stddev:0.99 (0.7%) stddev-sans-first:1.00 05:29:38 INFO - PID 14127 | Values: 150.0 150.0 152.0 150.0 150.0 152.0 150.0 152.0 152.0 151.0 05:29:38 INFO - PID 14127 | 05:29:38 INFO - PID 14127 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:94.50 stddev:3.14 (3.3%) stddev-sans-first:3.26 05:29:38 INFO - PID 14127 | Values: 94.0 104.0 94.0 98.0 94.0 94.0 96.0 94.0 96.0 95.0 05:29:38 INFO - PID 14127 | 05:29:38 INFO - PID 14127 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:53.50 stddev:1.64 (3.1%) stddev-sans-first:1.72 05:29:38 INFO - PID 14127 | Values: 53.0 53.0 53.0 54.0 53.0 53.0 58.0 54.0 52.0 54.0 05:29:38 INFO - PID 14127 | 05:29:38 INFO - PID 14127 | [#9] json-stringify-tinderbox Cycles:10 Average:54.80 Median:54.50 stddev:1.55 (2.8%) stddev-sans-first:1.64 05:29:38 INFO - PID 14127 | Values: 55.0 59.0 55.0 54.0 54.0 54.0 54.0 54.0 54.0 55.0 05:29:38 INFO - PID 14127 | 05:29:38 INFO - PID 14127 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:85.50 stddev:3.12 (3.6%) stddev-sans-first:3.09 05:29:38 INFO - PID 14127 | Values: 81.0 84.0 81.0 88.0 82.0 87.0 80.0 84.0 87.0 88.0 05:29:38 INFO - PID 14127 | 05:29:38 INFO - PID 14127 | [#11] stanford-crypto-ccm Cycles:10 Average:137.70 Median:140.50 stddev:13.65 (9.7%) stddev-sans-first:13.61 05:29:38 INFO - PID 14127 | Values: 151.0 131.0 143.0 128.0 125.0 141.0 126.0 140.0 125.0 167.0 05:29:38 INFO - PID 14127 | 05:29:38 INFO - PID 14127 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.70 Median:178.00 stddev:3.65 (2.1%) stddev-sans-first:3.82 05:29:38 INFO - PID 14127 | Values: 176.0 182.0 177.0 176.0 184.0 174.0 179.0 175.0 181.0 173.0 05:29:38 INFO - PID 14127 | 05:29:38 INFO - PID 14127 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:67.50 stddev:2.66 (3.9%) stddev-sans-first:2.82 05:29:38 INFO - PID 14127 | Values: 68.0 65.0 71.0 67.0 73.0 66.0 66.0 66.0 70.0 66.0 05:29:38 INFO - PID 14127 | -------- Summary: end -------- 05:29:38 INFO - PID 14127 | 05:29:39 INFO - PID 14127 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 05:29:39 INFO - TEST-INFO | 14127: exit 0 05:29:39 INFO - TEST-OK | kraken | took 62509ms 05:29:39 INFO - SUITE-END | took 1175s 05:29:39 INFO - Completed test suite (00:19:35) 05:29:39 INFO - javascript benchmark 05:29:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8543.323997760024, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3128.0459459864733, 3180.4593219152744, 3192.6966938140868, 3194.233383987843, 3249.463838466652, 6229.439350458458, 6255.499560035198, 6285.151504546885, 6302.495800335973, 6333.873322533558, 6301.779437719681, 6301.810945671626, 6326.968365158179, 6331.525135614825, 6332.3984221498995, 5737.827865164052, 5743.3108027036815, 5746.454086861741, 5764.077747560391, 5871.559633027518, 5749.655020698771, 5777.480026797587, 5787.392323806003, 5872.354041055481, 5872.823815285548, 5611.887762244753, 5655.26481557399, 5663.915041274384, 5782.479576838084, 5850.268716410449, 5740.282464691913, 5744.396838331999, 5757.769689212427, 5781.768729250825, 5786.450287222707, 5680.261565996394, 5711.143328500717, 5740.684262365568, 5807.157962095473, 5807.709614519257, 6174.9073763893575, 6189.752409903598, 6192.102145188967, 6251.499880009588, 6284.905726414107, 6652.53432259742, 6758.493113016494, 6763.256041835394, 6763.797086087426, 6766.560173588701, 6801.863962720768, 6810.080639113706, 6881.827954301132, 6883.827904302382, 6884.414824739903, 6262.686865656745, 6321.557490975633, 6332.905006424908, 6336.809895703136, 6338.0, 6930.237673855871, 6995.125609298838, 7007.4394048476, 7008.649567521604, 7032.824179395454, 6584.374484423972, 6615.37153970372, 6681.0, 6706.698198581075, 6707.094542236736, 6640.468762498988, 6738.831529211809, 6740.62926539045, 6752.054712340277, 6823.965880170567, 6602.1747281589805, 6640.402363787282, 6686.632235227012, 6690.531662783558, 6692.698828552727, 6668.099806526056, 6678.732850686015, 6684.0, 6696.19645642526, 6707.463402927754, 5405.540529054996, 5444.591655625764, 5458.8908221835345, 5465.863353416117, 5500.724963751796, 4613.700109492872, 4636.142313671981, 4673.0, 4705.364775755298, 4715.0, 5735.741891614887, 5779.3642699303045, 5821.621594596338, 5828.213191219216, 5860.677662728591, 6694.899576506356, 6701.0, 6704.497162712816, 6707.865842683216, 6726.630035348103, 5759.481646651821, 5760.193572899798, 5770.740316685759, 5772.365039845614, 5796.681182535001, 5090.669106508065, 5937.435943585352, 5985.22192115031, 6008.549358798107, 6031.276246850318, 6432.388923052303, 6445.129907462432, 6554.016897465323, 6561.179852518435, 6597.670116494156, 6693.531452798257, 6742.123523941857, 6745.291744366967, 6761.729530818712, 6843.794686159423, 6419.293877673359, 6471.514636402383, 6483.805485835433, 6494.9025764612625, 6497.837554061186, 6587.736490540325, 6604.207495100619, 6619.635920024445, 6631.33686631333, 6636.369544893228, 6460.903086453613, 6468.773592924225, 6740.9325906740305, 6803.897941530782, 6919.273476285119, 6541.738330466728, 6555.672216389257, 6646.202455705346, 6662.633555154319, 6666.200055993312, 6561.475081993524, 6563.310852360433, 6633.668316584248, 6652.966735166293, 6655.933440665532, 6413.0701048348665, 6499.707513161825, 6506.512011599244, 6517.217933847968, 6581.638009909501, 6505.024246363083, 6617.569857959217, 6623.370779776106, 6665.266820649627, 6737.663116844236, 6294.590851594632, 6338.746450141943, 6394.072859435449, 6398.90401643985, 6490.545661803629], "value": 6141.239484238578, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [829.0797215091247, 840.2353857989231, 859.7635650196197, 882.9072947340529, 884.579824583323, 41765.0, 42589.57410425895, 42634.14731705368, 42722.35916461251, 42997.78501107498, 39476.60523394765, 39712.80143599278, 39720.801395993054, 39725.20549589006, 39831.60168398315, 6884.0, 7069.187043489987, 7070.0, 7072.257412971641, 7077.71689132434, 6906.447484201251, 6985.231624521324, 7016.017757513952, 7033.964830175842, 7040.542364746275, 7778.5721785301785, 7947.841043179133, 7951.244631760002, 7968.243016913384, 7996.520208787462, 7833.334166595818, 7886.487378320391, 7887.566183859885, 7913.4460587758595, 7913.446058775888, 7575.242475752436, 7654.4259180561685, 7796.727114550963, 7835.725749598737, 7839.02012248469, 38474.61525384766, 38550.42174367387, 38675.41986870199, 38775.224495510214, 38931.0, 3377.696007359325, 3430.0395889151077, 3450.258194488197, 3453.689167974869, 3461.0, 31663.20841978969, 31730.841345793353, 31791.0, 31837.840810796028, 31863.52204716931, 4820.180569303227, 4966.2054071348775, 4976.502349765031, 4976.925346119811, 5009.724465154415, 176995.0, 177122.0, 182450.0, 183828.0, 185257.0, 51286.0, 51528.48471515312, 51668.0, 51670.483295167316, 51681.44955651336, 53337.46662533325, 53584.46415535873, 53663.463365366624, 53674.46325536773, 53736.73131634317, 16832.158392080346, 16896.831031689526, 17084.914575427043, 17108.572285692957, 17387.56531086733, 14387.42450301997, 14407.42370305197, 14409.567712968628, 14419.85580144185, 14461.421543138156, 14926.253687315591, 14960.551183464513, 14971.101733895783, 15067.773983390258, 15199.468018619516, 5682.176084467729, 5836.387179346192, 5901.13794809517, 5939.406059394026, 5966.346249925065, 949.3686698345523, 971.3103696375653, 972.5526257921294, 991.7917237380086, 995.049727510226, 22192.0, 22296.21963231279, 22324.55350892973, 22486.550268994855, 22579.322620321418, 1060.4273692206123, 1069.4599227390274, 1075.8870318616432, 1081.1296905990596, 1082.7780305037447, 11684.591039313584, 11691.473883675255, 11706.648800535997, 11751.059915206763, 11774.793367830298, 195155.0, 198695.0, 216080.0, 219127.0, 219432.0, 53581.0, 53938.19092713612, 54032.0, 54143.0, 54212.728936355066, 1469.8750606198594, 1480.7482727936276, 1495.2523738130717, 1497.236409431198, 1501.5195137556095, 21974.780252197274, 22020.0, 22216.88891555532, 22511.212107576157, 22528.887355563118, 36208.456873146395, 37517.2496550062, 37592.43611345887, 37610.0, 37611.2477750438, 179967.0, 181495.0, 181710.0, 182207.08896455434, 183390.0, 31232.843835780677, 31450.21374465657, 31486.685133148374, 31791.205219869687, 34687.653123468444, 44947.550524495644, 45323.54676453193, 45448.31827522656, 45520.0, 46293.305600416694, 33125.0, 33144.17139571434, 33356.332873342886, 33394.83302583471, 33937.0, 32199.0, 32420.513692295106, 32704.34591308208, 32717.18207044843, 33090.83454582808, 34763.65236347604, 36561.26877462489, 36565.634343656224, 36638.63361366352, 36903.0, 31501.0, 31993.36013279768, 32155.196120097185, 32259.0, 32327.51508727324, 22855.428614284778, 30309.696903030686, 30390.0, 31016.224594384417, 31059.689403106582], "value": 17192.76772259822, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.8163787587971, 575.1171951055131, 581.4941001328842, 588.1648059755147, 598.4195330529386, 22465.55068898623, 23379.298621041376, 23495.177668781616, 23563.5287294254, 24811.50376992459, 25942.0, 26537.469250615024, 26609.201723948263, 26637.733622663814, 26747.19758407251, 6428.550001499897, 6539.803805885831, 6585.407313341799, 6593.109930159415, 6610.272869984322, 5970.522358211333, 6441.935580644204, 6461.0, 6515.869682606369, 6543.9672801635925, 6420.133282006939, 6464.0, 6495.415412612864, 6495.5777874438245, 6509.414152726254, 6514.869702605945, 6520.576162549419, 6522.478201743849, 6527.314631963647, 6527.706253218569, 6039.094135879654, 6055.909161362583, 6056.5457590680435, 6061.636301821904, 6062.696865156725, 29029.12912612625, 29491.0, 30132.397352053056, 30748.53877191844, 31083.067507974796, 1960.4412742368356, 2001.039501039509, 2001.949951251216, 2002.0390212697841, 2002.1290738528642, 19412.514687132792, 19955.800441995685, 20045.1981920723, 20106.195752169897, 20119.39641810748, 2366.562185995579, 2425.927222183337, 2427.1747605814107, 2438.1344622659053, 2540.174443305933, 129945.35027324897, 133702.0, 133730.0, 133926.33036834752, 134285.0, 14308.0, 14916.328765205591, 15009.774853377208, 15032.473863414729, 16855.325786968384, 23806.523869522513, 23878.0, 23893.880530597584, 23948.161814336414, 23965.640515392282, 10047.397156170653, 10088.344257623232, 10217.948910255549, 10222.6422075227, 10428.478576071166, 8074.636641351153, 9411.811763764823, 9541.666041688508, 9552.513130135323, 9710.465924374086, 7989.880151797728, 9063.501507417155, 9396.812063758687, 9480.478573678376, 9498.810023799486, 2861.227468583471, 2863.110347170899, 2868.842213678226, 2889.4076714273524, 2955.1873234860154, 923.8244733500613, 968.4334664221509, 978.9853152202722, 984.3503287829998, 988.367444835306, 19279.325223617107, 19414.0, 19638.70541941872, 19771.209151633775, 19803.603927921362, 1014.8934361891895, 1055.377327376836, 1058.8305871060593, 1062.516554967488, 1065.9253852230424, 10717.732056698645, 10833.29583577065, 10882.183836212316, 10971.341719496855, 11198.888011119947, 529366.0, 535194.0, 539283.0, 542954.0, 547506.0, 120181.39909300572, 121088.39455802664, 121673.39163304126, 122347.0, 123298.0, 1252.1485389934933, 1280.801475771257, 1321.378951892609, 1325.5956933135487, 1326.4031185966353, 20133.6979945298, 20471.0, 20655.690164647243, 21007.159713611894, 21914.342569722932, 59273.0, 59518.70240648942, 59766.0, 60668.69665651643, 62296.0, 68615.0, 68896.31103688899, 69183.30816691769, 69272.30727692659, 69283.65358173176, 14917.47788827386, 15272.465463708717, 15377.538673839803, 15380.077195368314, 15431.0, 24120.638190427508, 24165.395865103514, 24326.51346973086, 24340.269791905564, 24616.876915615307, 18894.433167005012, 19102.33141840029, 19313.22747090157, 19352.903235483733, 19413.4175974721, 16389.672206555562, 16786.916065419595, 17508.0, 17738.29046838112, 17785.377511787025, 19114.0, 19664.508387290432, 19715.0, 19749.506262343555, 19800.405987820388, 15025.173615450818, 15110.471133510275, 15118.244087795787, 15129.697406052037, 15274.847251527342, 18713.15790789445, 18833.0, 18977.90511047436, 19205.903970480616, 19280.32518861833], "value": 12259.960843238636, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1354.8306461692287, 1369.8767110960011, 1383.8477767445581, 1408.0073548148557, 1413.3145424469124, 13674.726505469896, 14017.50938717145, 14226.075305105162, 14251.64370890727, 14302.713945721105, 14561.199134047623, 14841.406343746237, 14850.777238341434, 14854.628634284149, 14877.0, 2680.249530131561, 2752.8898844046316, 2753.5731961545994, 2753.6282601848793, 2754.793390495711, 2478.603423452248, 2567.255495906185, 2623.842569445839, 2630.289821748127, 2637.235201791478, 2743.8353698778037, 2762.9309267268377, 2764.060219525361, 2765.4883846488465, 2768.1142034548952, 2691.905783297585, 2705.837649741022, 2721.9047333343237, 2755.9035433759723, 2763.585462180669, 2610.778083862874, 2631.0922731657547, 2651.814372993891, 2664.0276299150864, 2683.449892771977, 14008.299585020708, 14042.508512202025, 14093.436262549485, 14103.929480352635, 14119.717605647933, 7428.665710043061, 7809.531428114275, 7822.530648161129, 7833.060032796101, 7838.686452541949, 5043.445221025684, 5200.401953775288, 5279.340082489689, 5355.6251062425645, 5365.087935051011, 1731.913404329791, 1749.5101371615967, 1767.1164417791103, 1792.0143920843484, 1796.5329014456336, 22611.208607698816, 22870.0, 22876.19933302343, 22887.313380598607, 22912.8232817882, 6117.1130186123655, 6307.2431308242385, 6323.27282362525, 6477.514186436036, 6539.313372095957, 15949.760753588473, 15991.68016639684, 15995.920020400057, 16005.919970400073, 16011.039337639779, 7300.196978332379, 7613.0, 7614.086309642878, 7617.771466856003, 7627.923720762832, 7028.332308430691, 7063.646817659096, 7068.081149450539, 7200.063991681048, 7246.384057355184, 5162.819301324511, 5271.551918087006, 5499.945000549943, 5635.3237611486065, 5672.829815105553, 1740.6083631182883, 1778.315348590802, 1781.6080462298273, 1783.7413575031808, 1829.2957211473679, 868.2533021601417, 902.3322741171617, 905.0677801864091, 911.7355966769695, 913.1644545241163, 7714.729984450517, 8297.12880147588, 8347.582620868932, 8350.958245208734, 8353.707620233263, 959.6353385713384, 965.9082387173207, 976.5654283843763, 980.8332583460829, 999.7200783780553, 4282.035414129717, 4423.159599676052, 5284.735763211901, 5309.177077552986, 5361.571074314045, 18948.14733337003, 19713.507162321057, 19756.506087347643, 19765.01174941247, 19775.505612359808, 19067.904660476885, 19301.80698193028, 19590.314338998065, 19623.705644415346, 19709.40871773849, 10364.429956352473, 10537.525811338508, 10669.093127084285, 10682.305650132717, 10704.983026612459, 8026.317762990211, 8239.382046346667, 8414.537200454033, 8498.447600905922, 8809.251213646656, 16412.953086641206, 16562.50312490627, 16686.49940501787, 16859.662806744043, 17061.232244548777, 16615.08617026075, 16658.0, 16706.677380554254, 16738.0, 16874.578135546708, 11090.112790976542, 11151.72120696989, 11207.551697931991, 11355.37545435009, 11370.488328025092, 11812.40937953116, 12103.33431661267, 12571.685707857378, 12740.490380384681, 12914.612561623166, 10303.742406439598, 10376.844347334645, 10454.947725261325, 10515.158787296845, 10877.945610271898, 10485.52815123306, 10534.52594633228, 10582.365058096539, 10833.783324333626, 10882.61910833117, 9359.953200233955, 9609.231261499202, 9623.326367154232, 9624.711258662252, 9625.71122866315, 7225.24134965821, 7269.672864721206, 7272.200057993722, 7274.599897005715, 7325.560466372035, 7094.822629434099, 7106.786796396116, 7130.786076417716, 7132.251113632994, 7214.531055481587], "value": 6439.84174786786, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.6234853875666, 504.8409750928457, 526.176533724721, 526.1949217696924, 528.1259515501844, 6241.188645476082, 6305.653189074605, 6335.398137176967, 6341.365863413656, 6398.392152745488, 5869.943410186164, 6115.938840611592, 6148.3851614838495, 6160.630362178261, 6162.691865406734, 4901.362822833027, 4951.0, 4968.602511799066, 4984.4018717753925, 4997.175466048097, 4966.453690094087, 5007.574356179729, 5023.598112151038, 5043.84868453947, 5063.341765570489, 4716.858494245178, 4848.830290939818, 4946.2655694950445, 4969.105560999019, 4986.476419975905, 4896.902061958741, 4914.11545921734, 4925.507449255082, 4961.5286547777905, 4964.801407943696, 4412.955870441287, 4445.73325600465, 4452.777361131963, 4485.102979404132, 4514.848713578017, 6087.625825927892, 6091.51267898567, 6123.050927106306, 6124.203853499062, 6155.353687862755, 5745.913811292834, 5751.223584816038, 5777.104548794901, 5782.219400380936, 5801.448862358111, 6468.644224567647, 6509.153810004716, 6548.70530826114, 6629.50836061887, 6636.502262330345, 4878.804847806084, 4879.292502587141, 4889.144399730033, 4891.804327826882, 4921.360223170965, 6865.5537390069985, 6873.725050997954, 6910.827229319257, 6916.930830691729, 6991.265917078634, 5029.698218106925, 5031.169856973558, 5054.620903432257, 5057.367829021372, 5066.909185396905, 5047.141985862412, 5095.97452012745, 5133.204353325241, 5144.485551444825, 5146.768395422215, 5718.7426565804635, 5722.685252311079, 5731.627444216113, 5738.885222295614, 5773.538116950718, 6571.868562628816, 6606.768763093269, 6613.14029176204, 6659.700313485905, 6742.662866856637, 1672.6152984813561, 1718.6734520441075, 1760.5246583422404, 1779.4483710049926, 1786.857051435882, 1173.724174818917, 1220.6267948557872, 1232.7904256276274, 1243.098753403775, 1243.247835059794, 5741.540676745851, 5758.337791153987, 5768.884622307531, 5819.679917604488, 5822.359540450547, 6550.0, 6655.5341126121675, 6685.465162786965, 6703.011708033712, 6703.223645733884, 5798.0, 5804.912926306109, 5851.531877449794, 5866.7653293868625, 5884.235049443545, 3575.982120089435, 3613.2050948791225, 3650.5254316938626, 3656.597774244831, 3679.5584529856055, 6519.119918810995, 6523.18460192476, 6587.538872278895, 6612.636305003271, 6637.838378283781, 6926.445884329241, 6970.198427180838, 6998.545094568837, 7038.120234970629, 7059.2940705928995, 6468.611883287018, 6505.73977040932, 6526.641034743135, 6530.542861999615, 6563.671816409161, 6750.464619012363, 6752.987051942248, 6771.695273712793, 6823.658817059227, 6866.313368663094, 6720.294369091175, 6744.460443164633, 6772.424343930821, 6786.423154031962, 6799.218089919723, 6460.353964603502, 6462.547621666627, 6467.385598368148, 6490.935090649222, 6540.607563546202, 4993.525615066563, 5006.499350064964, 5050.469700681522, 5070.036693028313, 5081.695098293966, 6742.561733487308, 6746.628935408403, 6792.93207067923, 6805.251422343637, 6887.862242755016, 6499.740010399721, 6510.056041873996, 6586.835329116619, 6605.702743376656, 6613.900791488035, 6502.869942601027, 6612.305707900794, 6617.139771829631, 6636.9004464932095, 6656.966715166393, 6084.239470066242, 6269.3417191196095, 6341.873162536631, 6409.262934762562, 6489.5781774184525, 6445.967770161306, 6509.772157974448, 6520.478361730955, 6568.540202185801, 6605.438537724347, 6302.369763023661, 6370.74517019314, 6401.807945761635, 6403.487720982217, 6411.351769966736], "value": 5076.005415970969, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [878.7583414560995, 879.5602198900546, 905.6875377994589, 910.2854259406367, 913.9634414623415, 18213.544661383454, 19122.904385478054, 19134.330298439556, 19145.52136196596, 19167.32914347998, 15868.682626347467, 16339.26473308701, 17538.12309384529, 20509.897450512763, 20550.69173962392, 1034.1571619130405, 1053.3837704942619, 1060.76132870104, 1062.4156713807413, 1064.8083344997897, 975.770693886936, 1012.0941757127404, 1057.5241141486363, 1063.9521221545049, 1064.8509208710786, 1098.4507746126937, 1116.0848104554268, 1116.8380584815197, 1118.9216754827166, 1119.5969450997634, 1101.0365929811414, 1115.1524841120724, 1115.2472081345059, 1118.1613789657756, 1120.8150655141892, 812.5490352854164, 813.8372325534916, 815.9714409995681, 816.0370762500247, 816.6529225079317, 17095.572610684707, 17227.741583876254, 19279.22883084675, 19447.513812154666, 19480.318188863464, 21589.892050539645, 21790.891045544828, 21829.563408731738, 22202.55594888093, 22215.777842221694, 16661.416850410176, 16745.91627041857, 16988.74516882248, 17028.574285642833, 17102.914485427616, 3540.3627347077518, 3618.6743193112484, 3634.727395445352, 3634.890953271406, 3639.1993761372455, 125071.0, 125814.37092814477, 129589.35205324102, 131212.0, 131693.0, 16587.087710176056, 16667.0, 16944.915275423544, 16980.320787168373, 17040.0, 28681.13956581306, 29763.85118074439, 30896.69103308938, 30963.84518077395, 30974.53538196929, 8649.351298652626, 8798.428102173339, 8804.339674524435, 8838.204561589488, 8874.911250887537, 3865.2269546090893, 3940.408938659224, 4011.799410029487, 4033.596640335943, 4035.9596404035583, 5228.477152284741, 5399.865003374947, 5409.26974858397, 5447.210154527571, 5456.7544460499375, 1050.6585359757955, 1079.5250089960393, 1094.1738987064716, 1101.7576133250673, 1109.8612673415823, 675.4191395399952, 690.3131384272681, 693.5803838677629, 704.968276427562, 708.7342246657504, 12219.572314968933, 12587.685307867376, 12622.242665440102, 12685.112042156961, 12708.682282943, 687.2714922182503, 688.1192074145102, 694.6387878303254, 697.870893884633, 699.9510034297653, 5591.245181900392, 5668.178114173505, 6089.817305480843, 6195.411435913581, 6327.841803954938, 99199.50400247953, 99299.0, 102221.48889255506, 103703.0, 104165.0, 90648.0, 90894.09105908856, 92474.53762731144, 92658.53670731603, 93317.53341233387, 76852.23147768562, 78937.21062789299, 80736.59631701803, 82065.0, 86932.13067869366, 12214.0, 12353.0, 12536.686582835502, 12724.936375318435, 12816.935915320364, 34435.0, 34969.0, 35634.0, 36007.45988810118, 37358.0, 46927.53072469232, 65185.0, 67718.0, 68784.0, 71942.64028679823, 18692.158852851382, 18810.52973675669, 19209.0, 19293.517662058563, 19444.611107778048, 18823.811761882753, 18867.90566047161, 20655.483612909797, 20979.265725699526, 21526.0, 18674.0, 19212.8078719211, 19243.13405896766, 19320.903395482932, 19565.608687826447, 18660.0, 18869.339573114874, 19090.04549772478, 19180.13689383953, 19381.0, 17906.731399028766, 18256.817431826043, 18480.0, 18497.72253416173, 18525.90737046306, 13644.931775341456, 13772.655683607589, 14050.578482645536, 14235.430582776988, 14289.356978935764, 14323.355449005028, 14619.0, 14660.340284687429, 14684.706305874037, 14688.09659460052], "value": 9188.538214559545, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1552.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 115.0, 103.0, 108.0, 109.0, 109.0, 115.0, 100.0, 108.0, 108.0], "value": 107.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 125.0, 146.0, 191.0, 121.0, 149.0, 154.0, 133.0, 176.0, 129.0], "value": 146.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 163.0, 171.0, 165.0, 163.0, 165.0, 167.0, 174.0, 169.0, 168.0], "value": 167.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 88.0, 74.0, 183.0, 75.0, 81.0, 75.0, 75.0, 169.0, 75.0], "value": 96.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 80.0, 139.0, 79.0, 79.0, 79.0, 130.0, 79.0, 81.0, 130.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 113.0, 114.0, 114.0, 114.0, 114.0, 117.0, 115.0, 115.0], "value": 114.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 152.0, 150.0, 150.0, 152.0, 150.0, 152.0, 152.0, 151.0], "value": 150.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 104.0, 94.0, 98.0, 94.0, 94.0, 96.0, 94.0, 96.0, 95.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 54.0, 53.0, 53.0, 58.0, 54.0, 52.0, 54.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 59.0, 55.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0, 55.0], "value": 54.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 81.0, 88.0, 82.0, 87.0, 80.0, 84.0, 87.0, 88.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 131.0, 143.0, 128.0, 125.0, 141.0, 126.0, 140.0, 125.0, 167.0], "value": 137.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 182.0, 177.0, 176.0, 184.0, 174.0, 179.0, 175.0, 181.0, 173.0], "value": 177.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 71.0, 67.0, 73.0, 66.0, 66.0, 66.0, 70.0, 66.0], "value": 67.8, "unit": "score"}]}]} 05:29:39 INFO - Return code: 0 05:29:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:29:39 INFO - # TBPL SUCCESS # 05:29:39 INFO - Running post-action listener: _package_coverage_data 05:29:39 INFO - Running post-action listener: _resource_record_post_action 05:29:39 INFO - [mozharness: 2017-08-08 12:29:39.954279Z] Finished run-tests step (success) 05:29:39 INFO - Running post-run listener: _resource_record_post_run 05:29:40 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 323514368; Read time: 12; Write time: 228264 05:29:40 INFO - TinderboxPrint: CPU usage
13.2% 05:29:40 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 05:29:40 INFO - TinderboxPrint: I/O write bytes / time
323,514,368 / 228,264 05:29:40 INFO - TinderboxPrint: CPU idle
8,265.1 (86.8%) 05:29:40 INFO - TinderboxPrint: CPU user
1,204.9 (12.7%) 05:29:40 INFO - TinderboxPrint: Swap in / out
0 / 0 05:29:40 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 405504; Read time: 0; Write time: 48 05:29:40 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:29:40 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 323002368; Read time: 0; Write time: 228216 05:29:40 INFO - Running post-run listener: _upload_blobber_files 05:29:40 INFO - Blob upload gear active. 05:29:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:29:40 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 05:29:40 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'] 05:29:40 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 05:29:41 INFO - (blobuploader) - INFO - Open directory for files ... 05:29:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:29:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:29:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:41 INFO - (blobuploader) - INFO - Done attempting. 05:29:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:29:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:29:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:48 INFO - (blobuploader) - INFO - Done attempting. 05:29:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:29:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:29:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:49 INFO - (blobuploader) - INFO - Done attempting. 05:29:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:29:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:29:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:50 INFO - (blobuploader) - INFO - Done attempting. 05:29:50 INFO - (blobuploader) - INFO - Iteration through files over. 05:29:50 INFO - Return code: 0 05:29:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:29:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:29:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fdc4f715277ed46fee8e8675d3bc4fb98ec723804e53db0c5e7ffe8e48502d9ca570dcc7a5419f521438f90fa1c696b5ecad9286420d07c3c1e8751cdb6e790", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b2d3b15082c6194f7accc494debf79c4201ab3867f78ff1af724e324cafc873f12811827a5e44ae534a6acaef4cc632c611ada756d2a65374fc95e55a174367", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b69bcc0651ef31959b160e47045bb092dc345dee2c06f8cd6bfc4b32d7871695881a5a4e80eb9458b2d315d78cd1995009d5aa3a79b5c6c3d2f4c56d73df6227", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/433ad641346f2662184df35f1a375936c6a8bdc6209370b9084d4dff48be71acae018804f1500069a03e4e9fc2cf2636a6de2b1b186c1f72bc271db894953a9a"} 05:29:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:29:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:29:50 INFO - Contents: 05:29:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fdc4f715277ed46fee8e8675d3bc4fb98ec723804e53db0c5e7ffe8e48502d9ca570dcc7a5419f521438f90fa1c696b5ecad9286420d07c3c1e8751cdb6e790", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b2d3b15082c6194f7accc494debf79c4201ab3867f78ff1af724e324cafc873f12811827a5e44ae534a6acaef4cc632c611ada756d2a65374fc95e55a174367", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b69bcc0651ef31959b160e47045bb092dc345dee2c06f8cd6bfc4b32d7871695881a5a4e80eb9458b2d315d78cd1995009d5aa3a79b5c6c3d2f4c56d73df6227", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/433ad641346f2662184df35f1a375936c6a8bdc6209370b9084d4dff48be71acae018804f1500069a03e4e9fc2cf2636a6de2b1b186c1f72bc271db894953a9a"} 05:29:50 INFO - Running post-run listener: copy_logs_to_upload_dir 05:29:50 INFO - Copying logs to upload dir... 05:29:50 INFO - mkdir: /builds/slave/test/build/upload/logs 05:29:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1439.454621 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 59 secs) (at 2017-08-08 05:29:49.985651) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-08 05:29:49.993349) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fdc4f715277ed46fee8e8675d3bc4fb98ec723804e53db0c5e7ffe8e48502d9ca570dcc7a5419f521438f90fa1c696b5ecad9286420d07c3c1e8751cdb6e790", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b2d3b15082c6194f7accc494debf79c4201ab3867f78ff1af724e324cafc873f12811827a5e44ae534a6acaef4cc632c611ada756d2a65374fc95e55a174367", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b69bcc0651ef31959b160e47045bb092dc345dee2c06f8cd6bfc4b32d7871695881a5a4e80eb9458b2d315d78cd1995009d5aa3a79b5c6c3d2f4c56d73df6227", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/433ad641346f2662184df35f1a375936c6a8bdc6209370b9084d4dff48be71acae018804f1500069a03e4e9fc2cf2636a6de2b1b186c1f72bc271db894953a9a"} build_url:https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011442 build_url: 'https://queue.taskcluster.net/v1/task/At2ih0kyQ7Ka5Inf4WhGAA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fdc4f715277ed46fee8e8675d3bc4fb98ec723804e53db0c5e7ffe8e48502d9ca570dcc7a5419f521438f90fa1c696b5ecad9286420d07c3c1e8751cdb6e790", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b2d3b15082c6194f7accc494debf79c4201ab3867f78ff1af724e324cafc873f12811827a5e44ae534a6acaef4cc632c611ada756d2a65374fc95e55a174367", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b69bcc0651ef31959b160e47045bb092dc345dee2c06f8cd6bfc4b32d7871695881a5a4e80eb9458b2d315d78cd1995009d5aa3a79b5c6c3d2f4c56d73df6227", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/433ad641346f2662184df35f1a375936c6a8bdc6209370b9084d4dff48be71acae018804f1500069a03e4e9fc2cf2636a6de2b1b186c1f72bc271db894953a9a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-08 05:29:50.017774) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:29:50.021103) ========= 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=88e9d754c8c9af9f7805d7f3000001bd-1502193402.706415-785145471 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004425 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:29:50.101683) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-08 05:29:50.105019) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-08 05:29:50.105313) ========= ========= Total master_lag: 0.28 =========