builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-022 starttime: 1504186421.77 results: success (0) revision: fb22415719a9d971a2646fa2d1b74e134ca00c3d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-31 06:33:41.774984) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-31 06:33:41.775684) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-31 06:33:41.776161) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-31 06:33:41.790544) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 06:33:41.790929) ========= 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=930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-31 06:33:41-- 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% 50.0M=0s 2017-08-31 06:33:41 (50.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101823 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 06:33:41.910627) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 06:33:41.911133) ========= 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=930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016412 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 06:33:41.965208) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-31 06:33:41.965558) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fb22415719a9d971a2646fa2d1b74e134ca00c3d --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 fb22415719a9d971a2646fa2d1b74e134ca00c3d --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=930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-31 06:33:41,996 truncating revision to first 12 chars 2017-08-31 06:33:41,996 Setting DEBUG logging. 2017-08-31 06:33:41,996 attempt 1/10 2017-08-31 06:33:41,996 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fb22415719a9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-31 06:33:43,270 unpacking tar archive at: mozilla-central-fb22415719a9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.585483 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-31 06:33:43.566285) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-31 06:33:43.566617) ========= script_repo_revision: fb22415719a9d971a2646fa2d1b74e134ca00c3d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-31 06:33:43.566983) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-31 06:33:43.567256) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-31 06:33:43.578733) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-08-31 06:33:43.579119) ========= /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=930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:33:43 INFO - MultiFileLogger online at 20170831 06:33:43 in /builds/slave/test 06:33:43 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 06:33:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:33:43 INFO - {'append_to_log': False, 06:33:43 INFO - 'base_work_dir': '/builds/slave/test', 06:33:43 INFO - 'blob_upload_branch': 'Firefox', 06:33:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:33:43 INFO - 'branch': 'Firefox', 06:33:43 INFO - 'buildbot_json_path': 'buildprops.json', 06:33:43 INFO - 'code_coverage': False, 06:33:43 INFO - 'config_files': ('talos/linux_config.py',), 06:33:43 INFO - 'default_actions': ('clobber', 06:33:43 INFO - 'read-buildbot-config', 06:33:43 INFO - 'download-and-extract', 06:33:43 INFO - 'populate-webroot', 06:33:43 INFO - 'create-virtualenv', 06:33:43 INFO - 'install', 06:33:43 INFO - 'setup-mitmproxy', 06:33:43 INFO - 'run-tests'), 06:33:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:33:43 INFO - 'disable_ccov_upload': False, 06:33:43 INFO - 'disable_stylo': False, 06:33:43 INFO - 'download_minidump_stackwalk': True, 06:33:43 INFO - 'download_symbols': 'ondemand', 06:33:43 INFO - 'enable_stylo': False, 06:33:43 INFO - 'enable_webrender': False, 06:33:43 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 06:33:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:33:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:33:43 INFO - 'gecko_profile': False, 06:33:43 INFO - 'gecko_profile_interval': 0, 06:33:43 INFO - 'installer_path': 'installer.exe', 06:33:43 INFO - 'log_level': 'info', 06:33:43 INFO - 'log_name': 'talos', 06:33:43 INFO - 'log_to_console': True, 06:33:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:33:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:33:43 INFO - 'opt_config_files': (), 06:33:43 INFO - 'pip_index': False, 06:33:43 INFO - 'suite': 'dromaeojs-e10s', 06:33:43 INFO - 'system_bits': '32', 06:33:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:33:43 INFO - 'title': 'talos-linux64-ix-022', 06:33:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:33:43 INFO - 'use_talos_json': True, 06:33:43 INFO - 'verify': 'False', 06:33:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:33:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:33:43 INFO - 'work_dir': 'build'} 06:33:43 INFO - [mozharness: 2017-08-31 13:33:43.752144Z] Running clobber step. 06:33:43 INFO - Running pre-action listener: _resource_record_pre_action 06:33:43 INFO - Running main action method: clobber 06:33:43 INFO - rmtree: /builds/slave/test/build 06:33:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:33:44 INFO - Running post-action listener: _resource_record_post_action 06:33:44 INFO - [mozharness: 2017-08-31 13:33:44.827589Z] Finished clobber step (success) 06:33:44 INFO - [mozharness: 2017-08-31 13:33:44.827686Z] Running read-buildbot-config step. 06:33:44 INFO - Running pre-action listener: _resource_record_pre_action 06:33:44 INFO - Running main action method: read_buildbot_config 06:33:44 INFO - Using buildbot properties: 06:33:44 INFO - { 06:33:44 INFO - "project": "", 06:33:44 INFO - "product": "firefox", 06:33:44 INFO - "who": "archaeopteryx@coole-files.de", 06:33:44 INFO - "installer_path": "public/build/target.tar.bz2", 06:33:44 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 06:33:44 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 06:33:44 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 06:33:44 INFO - "stage_platform": "linux64-stylosequential", 06:33:44 INFO - "basedir": "/builds/slave/test", 06:33:44 INFO - "buildnumber": 220, 06:33:44 INFO - "platform": "ubuntu64_hw_styloseq", 06:33:44 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:33:44 INFO - "slavebuilddir": "test", 06:33:44 INFO - "taskId": "HD0Juh4uSNiXxSssAOdS_g", 06:33:44 INFO - "branch": "mozilla-central", 06:33:44 INFO - "script_repo_revision": "production", 06:33:44 INFO - "revision": "fb22415719a9d971a2646fa2d1b74e134ca00c3d", 06:33:44 INFO - "slavename": "talos-linux64-ix-022", 06:33:44 INFO - "repo_path": "mozilla-central" 06:33:44 INFO - } 06:33:44 INFO - Finding installer, test and symbols from parent task. 06:33:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HD0Juh4uSNiXxSssAOdS_g'}, attempt #1 06:33:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HD0Juh4uSNiXxSssAOdS_g'}, attempt #1 06:33:45 INFO - Task dependencies: RI4nAJyITZ2MQzniRVqbBQ 06:33:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ'}, attempt #1 06:33:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.tar.bz2 06:33:46 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.test_packages.json 06:33:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.crashreporter-symbols.zip 06:33:46 INFO - Running post-action listener: _resource_record_post_action 06:33:46 INFO - [mozharness: 2017-08-31 13:33:46.241764Z] Finished read-buildbot-config step (success) 06:33:46 INFO - [mozharness: 2017-08-31 13:33:46.241982Z] Running download-and-extract step. 06:33:46 INFO - Running pre-action listener: _resource_record_pre_action 06:33:46 INFO - Running main action method: download_and_extract 06:33:46 INFO - mkdir: /builds/slave/test/build/tests 06:33:46 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')]} 06:33:46 INFO - trying https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.test_packages.json 06:33:46 INFO - Downloading https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 06:33:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 06:33:46 INFO - Downloaded 989 bytes. 06:33:46 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 06:33:46 INFO - Using the following test package requirements: 06:33:46 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 06:33:46 INFO - u'common': [u'target.common.tests.zip'], 06:33:46 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 06:33:46 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 06:33:46 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 06:33:46 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 06:33:46 INFO - u'mozbase': [u'target.common.tests.zip'], 06:33:46 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 06:33:46 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 06:33:46 INFO - u'web-platform': [u'target.common.tests.zip', 06:33:46 INFO - u'target.web-platform.tests.tar.gz'], 06:33:46 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 06:33:46 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 06:33:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.common.tests.zip 06:33:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:33:46 INFO - Fetch https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.common.tests.zip into memory 06:33:47 INFO - Content-Length response header: 54932979 06:33:47 INFO - Bytes received: 54932979 06:33:58 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 06:33:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.common.tests.zip 06:33:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 06:33:58 INFO - Fetch https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.common.tests.zip into memory 06:33:59 INFO - Content-Length response header: 54932979 06:33:59 INFO - Bytes received: 54932979 06:34:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.talos.tests.zip 06:34:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 06:34:13 INFO - Fetch https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.talos.tests.zip into memory 06:34:14 INFO - Content-Length response header: 14496650 06:34:14 INFO - Bytes received: 14496650 06:34:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:34:14 INFO - trying https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.tar.bz2 06:34:14 INFO - Downloading https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 06:34:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 06:34:15 INFO - Downloaded 62671715 bytes. 06:34:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.tar.bz2 06:34:15 INFO - mkdir: /builds/slave/test/properties 06:34:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:34:15 INFO - Writing to file /builds/slave/test/properties/build_url 06:34:15 INFO - Contents: 06:34:15 INFO - build_url:https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.tar.bz2 06:34:15 INFO - Running post-action listener: _resource_record_post_action 06:34:15 INFO - Running post-action listener: find_tests_for_verification 06:34:15 INFO - Running post-action listener: set_extra_try_arguments 06:34:15 INFO - [mozharness: 2017-08-31 13:34:15.595951Z] Finished download-and-extract step (success) 06:34:15 INFO - [mozharness: 2017-08-31 13:34:15.596047Z] Running populate-webroot step. 06:34:15 INFO - Running pre-action listener: _resource_record_pre_action 06:34:15 INFO - Running main action method: populate_webroot 06:34:15 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:34:15 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 06:34:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:34:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:34:15 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 06:34:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:34:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:34:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:34:15 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 06:34:15 INFO - 'talos_options': ['--enable-stylo'], 06:34:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:34:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:34:15 INFO - 'tests': ['damp', 'tps']}, 06:34:15 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 06:34:15 INFO - 'talos_options': ['--enable-stylo'], 06:34:15 INFO - 'tests': ['damp', 'tps']}, 06:34:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:34:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 06:34:15 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 06:34:15 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 06:34:15 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 06:34:15 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 06:34:15 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 06:34:15 INFO - 'talos_options': ['--enable-stylo'], 06:34:15 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 06:34:15 INFO - 'other-e10s': {'tests': ['a11yr', 06:34:15 INFO - 'ts_paint', 06:34:15 INFO - 'tpaint', 06:34:15 INFO - 'sessionrestore', 06:34:15 INFO - 'sessionrestore_many_windows', 06:34:15 INFO - 'sessionrestore_no_auto_restore', 06:34:15 INFO - 'tabpaint']}, 06:34:15 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 06:34:15 INFO - 'tests': ['a11yr', 06:34:15 INFO - 'ts_paint', 06:34:15 INFO - 'tpaint', 06:34:15 INFO - 'sessionrestore', 06:34:15 INFO - 'sessionrestore_many_windows', 06:34:15 INFO - 'sessionrestore_no_auto_restore', 06:34:15 INFO - 'tabpaint']}, 06:34:15 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 06:34:15 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 06:34:15 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 06:34:15 INFO - 'tests': ['bloom_basic_singleton']}, 06:34:15 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 06:34:15 INFO - 'tests': ['bloom_basic']}, 06:34:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:34:15 INFO - 'tsvgr_opacity', 06:34:15 INFO - 'tart', 06:34:15 INFO - 'tscrollx', 06:34:15 INFO - 'tsvg_static']}, 06:34:15 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 06:34:15 INFO - 'tests': ['tsvgx', 06:34:15 INFO - 'tsvgr_opacity', 06:34:15 INFO - 'tart', 06:34:15 INFO - 'tscrollx', 06:34:15 INFO - 'tsvg_static']}, 06:34:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:34:15 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 06:34:15 INFO - 'talos_options': ['--enable-stylo'], 06:34:15 INFO - 'tests': ['tp5o']}, 06:34:15 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 06:34:15 INFO - 'talos_options': ['--mitmproxy', 06:34:15 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 06:34:15 INFO - '--firstNonBlankPaint'], 06:34:15 INFO - 'tests': ['tp6_google', 06:34:15 INFO - 'tp6_youtube', 06:34:15 INFO - 'tp6_amazon', 06:34:15 INFO - 'tp6_facebook']}, 06:34:15 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 06:34:15 INFO - 'talos_options': ['--enable-stylo', 06:34:15 INFO - '--mitmproxy', 06:34:15 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 06:34:15 INFO - '--firstNonBlankPaint'], 06:34:15 INFO - 'tests': ['tp6_google', 06:34:15 INFO - 'tp6_youtube', 06:34:15 INFO - 'tp6_amazon', 06:34:15 INFO - 'tp6_facebook']}, 06:34:15 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 06:34:15 INFO - 'talos_options': ['--enable-stylo', 06:34:15 INFO - '--stylo-threads=1', 06:34:15 INFO - '--mitmproxy', 06:34:15 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 06:34:15 INFO - '--firstNonBlankPaint'], 06:34:15 INFO - 'tests': ['tp6_google', 06:34:15 INFO - 'tp6_youtube', 06:34:15 INFO - 'tp6_amazon', 06:34:15 INFO - 'tp6_facebook']}, 06:34:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:34:15 INFO - 'talos_options': ['--xperf_path', 06:34:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:34:15 INFO - 'tests': ['tp5n']}, 06:34:15 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 06:34:15 INFO - 'talos_options': ['--enable-stylo', 06:34:15 INFO - '--xperf_path', 06:34:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:34:15 INFO - 'tests': ['tp5n']}}} 06:34:15 INFO - Running post-action listener: _resource_record_post_action 06:34:15 INFO - [mozharness: 2017-08-31 13:34:15.610305Z] Finished populate-webroot step (success) 06:34:15 INFO - [mozharness: 2017-08-31 13:34:15.610409Z] Running create-virtualenv step. 06:34:15 INFO - Running pre-action listener: _resource_record_pre_action 06:34:15 INFO - Running main action method: create_virtualenv 06:34:15 INFO - Creating virtualenv /builds/slave/test/build/venv 06:34:15 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:34:15 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 06:34:15 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 06:34:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:34:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:34:16 INFO - Installing distribute.............................................................................................................................................................................................done. 06:34:16 INFO - Installing pip...............done. 06:34:16 INFO - Return code: 0 06:34:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:34:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:34:16 INFO - Reading from file tmpfile_stdout 06:34:16 INFO - Output received: 06:34:16 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 06:34:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:34:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:16 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 0x26781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2840a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4580>, '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 0x29e5480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5910>, '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': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', '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 06:34:16 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 06:34:16 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 06:34:16 INFO - Using env: {'DISPLAY': ':0', 06:34:16 INFO - 'HOME': '/home/cltbld', 06:34:16 INFO - 'LANG': 'en_US.UTF-8', 06:34:16 INFO - 'LANGUAGE': 'en_US:en', 06:34:16 INFO - 'LOGNAME': 'cltbld', 06:34:16 INFO - 'MAIL': '/var/mail/cltbld', 06:34:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:16 INFO - 'MOZ_NO_REMOTE': '1', 06:34:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:16 INFO - 'NO_EM_RESTART': '1', 06:34:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:16 INFO - 'PWD': '/builds/slave/test', 06:34:16 INFO - 'SHELL': '/bin/bash', 06:34:16 INFO - 'SHLVL': '1', 06:34:16 INFO - 'TERM': 'linux', 06:34:16 INFO - 'TMOUT': '86400', 06:34:16 INFO - 'USER': 'cltbld', 06:34:16 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', 06:34:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:16 INFO - '_': '/tools/buildbot/bin/python'} 06:34:17 INFO - Ignoring indexes: http://pypi.python.org/simple/ 06:34:17 INFO - Downloading/unpacking pip>=1.5 06:34:17 INFO - Running setup.py egg_info for package pip 06:34:17 INFO - warning: no files found matching 'pip/cacert.pem' 06:34:17 INFO - warning: no files found matching '*.html' under directory 'docs' 06:34:17 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 06:34:17 INFO - no previously-included directories found matching 'docs/_build/_sources' 06:34:17 INFO - Installing collected packages: pip 06:34:17 INFO - Found existing installation: pip 1.1 06:34:17 INFO - Uninstalling pip: 06:34:17 INFO - Successfully uninstalled pip 06:34:17 INFO - Running setup.py install for pip 06:34:17 INFO - warning: no files found matching 'pip/cacert.pem' 06:34:17 INFO - warning: no files found matching '*.html' under directory 'docs' 06:34:17 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 06:34:17 INFO - no previously-included directories found matching 'docs/_build/_sources' 06:34:17 INFO - Installing pip script to /builds/slave/test/build/venv/bin 06:34:17 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 06:34:17 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 06:34:17 INFO - Successfully installed pip 06:34:17 INFO - Cleaning up... 06:34:17 INFO - Return code: 0 06:34:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:34:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:17 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 0x26781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2840a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4580>, '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 0x29e5480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5910>, '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': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', '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 06:34:17 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 06:34:17 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 06:34:17 INFO - Using env: {'DISPLAY': ':0', 06:34:17 INFO - 'HOME': '/home/cltbld', 06:34:17 INFO - 'LANG': 'en_US.UTF-8', 06:34:17 INFO - 'LANGUAGE': 'en_US:en', 06:34:17 INFO - 'LOGNAME': 'cltbld', 06:34:17 INFO - 'MAIL': '/var/mail/cltbld', 06:34:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:17 INFO - 'MOZ_NO_REMOTE': '1', 06:34:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:17 INFO - 'NO_EM_RESTART': '1', 06:34:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:17 INFO - 'PWD': '/builds/slave/test', 06:34:17 INFO - 'SHELL': '/bin/bash', 06:34:17 INFO - 'SHLVL': '1', 06:34:17 INFO - 'TERM': 'linux', 06:34:17 INFO - 'TMOUT': '86400', 06:34:17 INFO - 'USER': 'cltbld', 06:34:17 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', 06:34:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:17 INFO - '_': '/tools/buildbot/bin/python'} 06:34:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:17 INFO - Downloading/unpacking psutil>=3.1.1 06:34:17 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 06:34:17 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 06:34:17 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 06:34:17 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 06:34:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:34:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:34:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:34:19 INFO - Installing collected packages: psutil 06:34:19 INFO - Running setup.py install for psutil 06:34:19 INFO - building 'psutil._psutil_linux' extension 06:34:19 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 06:34:19 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 06:34:19 INFO - building 'psutil._psutil_posix' extension 06:34:19 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 06:34:19 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 06:34:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:34:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:34:19 INFO - Successfully installed psutil 06:34:19 INFO - Cleaning up... 06:34:19 INFO - Return code: 0 06:34:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:34:19 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')]} 06:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:19 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')]} 06:34:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:19 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 0x26781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2840a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4580>, '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 0x29e5480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5910>, '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': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', '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 06:34:19 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 06:34:19 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 06:34:19 INFO - Using env: {'DISPLAY': ':0', 06:34:19 INFO - 'HOME': '/home/cltbld', 06:34:19 INFO - 'LANG': 'en_US.UTF-8', 06:34:19 INFO - 'LANGUAGE': 'en_US:en', 06:34:19 INFO - 'LOGNAME': 'cltbld', 06:34:19 INFO - 'MAIL': '/var/mail/cltbld', 06:34:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:19 INFO - 'MOZ_NO_REMOTE': '1', 06:34:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:19 INFO - 'NO_EM_RESTART': '1', 06:34:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:19 INFO - 'PWD': '/builds/slave/test', 06:34:19 INFO - 'SHELL': '/bin/bash', 06:34:19 INFO - 'SHLVL': '1', 06:34:19 INFO - 'TERM': 'linux', 06:34:19 INFO - 'TMOUT': '86400', 06:34:19 INFO - 'USER': 'cltbld', 06:34:19 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', 06:34:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:19 INFO - '_': '/tools/buildbot/bin/python'} 06:34:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:34:19 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 06:34:19 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 06:34:19 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 06:34:19 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 06:34:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:34:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:34:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:34:21 INFO - Installing collected packages: mozsystemmonitor 06:34:21 INFO - Running setup.py install for mozsystemmonitor 06:34:21 INFO - Successfully installed mozsystemmonitor 06:34:21 INFO - Cleaning up... 06:34:21 INFO - Return code: 0 06:34:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:34:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:34:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:34:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:21 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 0x26781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2840a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4580>, '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 0x29e5480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5910>, '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': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', '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 06:34:21 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 06:34:21 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 06:34:21 INFO - Using env: {'DISPLAY': ':0', 06:34:21 INFO - 'HOME': '/home/cltbld', 06:34:21 INFO - 'LANG': 'en_US.UTF-8', 06:34:21 INFO - 'LANGUAGE': 'en_US:en', 06:34:21 INFO - 'LOGNAME': 'cltbld', 06:34:21 INFO - 'MAIL': '/var/mail/cltbld', 06:34:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:21 INFO - 'MOZ_NO_REMOTE': '1', 06:34:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:21 INFO - 'NO_EM_RESTART': '1', 06:34:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:21 INFO - 'PWD': '/builds/slave/test', 06:34:21 INFO - 'SHELL': '/bin/bash', 06:34:21 INFO - 'SHLVL': '1', 06:34:21 INFO - 'TERM': 'linux', 06:34:21 INFO - 'TMOUT': '86400', 06:34:21 INFO - 'USER': 'cltbld', 06:34:21 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', 06:34:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:21 INFO - '_': '/tools/buildbot/bin/python'} 06:34:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:21 INFO - Downloading/unpacking jsonschema==2.5.1 06:34:21 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 06:34:21 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 06:34:21 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 06:34:21 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 06:34:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:34:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:34:22 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 06:34:22 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 06:34:22 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 06:34:22 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 06:34:22 INFO - Downloading functools32-3.2.3-2.tar.gz 06:34:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:34:22 INFO - warning: no files found matching '*.txt' 06:34:22 INFO - no previously-included directories found matching 'build' 06:34:22 INFO - no previously-included directories found matching 'dist' 06:34:22 INFO - no previously-included directories found matching '.git*' 06:34:22 INFO - Installing collected packages: jsonschema, functools32 06:34:22 INFO - Running setup.py install for functools32 06:34:22 INFO - warning: no files found matching '*.txt' 06:34:22 INFO - no previously-included directories found matching 'build' 06:34:22 INFO - no previously-included directories found matching 'dist' 06:34:22 INFO - no previously-included directories found matching '.git*' 06:34:22 INFO - Successfully installed jsonschema functools32 06:34:22 INFO - Cleaning up... 06:34:22 INFO - Return code: 0 06:34:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:34: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')]} 06:34:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34: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')]} 06:34:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:22 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 0x26781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2840a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4580>, '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 0x29e5480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5910>, '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': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', '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 06:34:22 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 06:34:22 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 06:34:22 INFO - Using env: {'DISPLAY': ':0', 06:34:22 INFO - 'HOME': '/home/cltbld', 06:34:22 INFO - 'LANG': 'en_US.UTF-8', 06:34:22 INFO - 'LANGUAGE': 'en_US:en', 06:34:22 INFO - 'LOGNAME': 'cltbld', 06:34:22 INFO - 'MAIL': '/var/mail/cltbld', 06:34:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:22 INFO - 'MOZ_NO_REMOTE': '1', 06:34:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:22 INFO - 'NO_EM_RESTART': '1', 06:34:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:22 INFO - 'PWD': '/builds/slave/test', 06:34:22 INFO - 'SHELL': '/bin/bash', 06:34:22 INFO - 'SHLVL': '1', 06:34:22 INFO - 'TERM': 'linux', 06:34:22 INFO - 'TMOUT': '86400', 06:34:22 INFO - 'USER': 'cltbld', 06:34:22 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', 06:34:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:22 INFO - '_': '/tools/buildbot/bin/python'} 06:34:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:34:23 INFO - Cleaning up... 06:34:23 INFO - Return code: 0 06:34:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:34:23 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')]} 06:34:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:23 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')]} 06:34:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:23 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 0x26781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2840a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4580>, '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 0x29e5480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5910>, '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': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', '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 06:34:23 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 06:34:23 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 06:34:23 INFO - Using env: {'DISPLAY': ':0', 06:34:23 INFO - 'HOME': '/home/cltbld', 06:34:23 INFO - 'LANG': 'en_US.UTF-8', 06:34:23 INFO - 'LANGUAGE': 'en_US:en', 06:34:23 INFO - 'LOGNAME': 'cltbld', 06:34:23 INFO - 'MAIL': '/var/mail/cltbld', 06:34:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:23 INFO - 'MOZ_NO_REMOTE': '1', 06:34:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:23 INFO - 'NO_EM_RESTART': '1', 06:34:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:23 INFO - 'PWD': '/builds/slave/test', 06:34:23 INFO - 'SHELL': '/bin/bash', 06:34:23 INFO - 'SHLVL': '1', 06:34:23 INFO - 'TERM': 'linux', 06:34:23 INFO - 'TMOUT': '86400', 06:34:23 INFO - 'USER': 'cltbld', 06:34:23 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', 06:34:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:23 INFO - '_': '/tools/buildbot/bin/python'} 06:34:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:23 INFO - Downloading/unpacking blobuploader==1.2.4 06:34:23 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 06:34:23 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 06:34:23 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 06:34:23 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 06:34:24 INFO - Downloading blobuploader-1.2.4.tar.gz 06:34:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:34:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:34:24 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 06:34:24 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 06:34:24 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 06:34:24 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 06:34:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:34:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:34:24 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 06:34:24 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 06:34:24 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 06:34:24 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 06:34:24 INFO - Downloading docopt-0.6.1.tar.gz 06:34:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:34:25 INFO - Installing collected packages: blobuploader, requests, docopt 06:34:25 INFO - Running setup.py install for blobuploader 06:34:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:34:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:34:25 INFO - Running setup.py install for requests 06:34:25 INFO - Running setup.py install for docopt 06:34:25 INFO - Successfully installed blobuploader requests docopt 06:34:25 INFO - Cleaning up... 06:34:25 INFO - Return code: 0 06:34:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:34:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:34:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:25 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 0x26781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2840a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4580>, '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 0x29e5480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5910>, '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': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', '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 06:34:25 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 06:34:25 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 06:34:25 INFO - Using env: {'DISPLAY': ':0', 06:34:25 INFO - 'HOME': '/home/cltbld', 06:34:25 INFO - 'LANG': 'en_US.UTF-8', 06:34:25 INFO - 'LANGUAGE': 'en_US:en', 06:34:25 INFO - 'LOGNAME': 'cltbld', 06:34:25 INFO - 'MAIL': '/var/mail/cltbld', 06:34:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:25 INFO - 'MOZ_NO_REMOTE': '1', 06:34:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:25 INFO - 'NO_EM_RESTART': '1', 06:34:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:25 INFO - 'PWD': '/builds/slave/test', 06:34:25 INFO - 'SHELL': '/bin/bash', 06:34:25 INFO - 'SHLVL': '1', 06:34:25 INFO - 'TERM': 'linux', 06:34:25 INFO - 'TMOUT': '86400', 06:34:25 INFO - 'USER': 'cltbld', 06:34:25 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', 06:34:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:25 INFO - '_': '/tools/buildbot/bin/python'} 06:34:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:34:25 INFO - Running setup.py (path:/tmp/pip-LSxIP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:34:25 INFO - Running setup.py (path:/tmp/pip-idp_KH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:34:25 INFO - Running setup.py (path:/tmp/pip-RH2MM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:34:26 INFO - Running setup.py (path:/tmp/pip-E_K6ZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:34:26 INFO - Running setup.py (path:/tmp/pip-e5Ij7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:34:26 INFO - Running setup.py (path:/tmp/pip-8tKTpo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:34:26 INFO - Running setup.py (path:/tmp/pip-Z7y0Nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:34:26 INFO - Running setup.py (path:/tmp/pip-RM2PFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:34:26 INFO - Running setup.py (path:/tmp/pip-w11ZWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:34:26 INFO - Running setup.py (path:/tmp/pip-luHSvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:34:26 INFO - Running setup.py (path:/tmp/pip-Dv9_py-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:34:26 INFO - Running setup.py (path:/tmp/pip-O9iGqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:34:26 INFO - Running setup.py (path:/tmp/pip-9KEkre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:34:26 INFO - Running setup.py (path:/tmp/pip-DlZOzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:34:26 INFO - Running setup.py (path:/tmp/pip-6cYXNS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:34:26 INFO - Running setup.py (path:/tmp/pip-IEbgqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:34:26 INFO - Running setup.py (path:/tmp/pip-u5DOZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:34:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:34:27 INFO - Running setup.py install for manifestparser 06:34:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:34:27 INFO - Running setup.py install for mozcrash 06:34:27 INFO - Running setup.py install for mozdebug 06:34:27 INFO - Running setup.py install for mozdevice 06:34:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:34:27 INFO - Running setup.py install for mozfile 06:34:27 INFO - Running setup.py install for mozhttpd 06:34:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:34:27 INFO - Running setup.py install for mozinfo 06:34:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:34:27 INFO - Running setup.py install for mozInstall 06:34:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:34:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:34:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:34:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:34:27 INFO - Running setup.py install for mozleak 06:34:27 INFO - Running setup.py install for mozlog 06:34:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:34:28 INFO - Running setup.py install for moznetwork 06:34:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:34:28 INFO - Running setup.py install for mozprocess 06:34:28 INFO - Running setup.py install for mozprofile 06:34:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:34:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:34:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:34:28 INFO - Running setup.py install for mozrunner 06:34:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:34:28 INFO - Running setup.py install for mozscreenshot 06:34:28 INFO - Running setup.py install for moztest 06:34:28 INFO - Running setup.py install for mozversion 06:34:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:34:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:34:28 INFO - Cleaning up... 06:34:28 INFO - Return code: 0 06:34:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:34:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:34:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:28 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 0x26781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2840a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4580>, '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 0x29e5480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5910>, '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': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', '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 06:34:28 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 06:34:28 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 06:34:28 INFO - Using env: {'DISPLAY': ':0', 06:34:28 INFO - 'HOME': '/home/cltbld', 06:34:28 INFO - 'LANG': 'en_US.UTF-8', 06:34:28 INFO - 'LANGUAGE': 'en_US:en', 06:34:28 INFO - 'LOGNAME': 'cltbld', 06:34:28 INFO - 'MAIL': '/var/mail/cltbld', 06:34:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:28 INFO - 'MOZ_NO_REMOTE': '1', 06:34:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:28 INFO - 'NO_EM_RESTART': '1', 06:34:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:28 INFO - 'PWD': '/builds/slave/test', 06:34:28 INFO - 'SHELL': '/bin/bash', 06:34:28 INFO - 'SHLVL': '1', 06:34:28 INFO - 'TERM': 'linux', 06:34:28 INFO - 'TMOUT': '86400', 06:34:28 INFO - 'USER': 'cltbld', 06:34:28 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', 06:34:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:28 INFO - '_': '/tools/buildbot/bin/python'} 06:34:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:34:28 INFO - Running setup.py (path:/tmp/pip-JSJhmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:34:29 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)) 06:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:34:29 INFO - Running setup.py (path:/tmp/pip-Jf0BRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:34:29 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)) 06:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:34:29 INFO - Running setup.py (path:/tmp/pip-D9VdKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:34:29 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)) 06:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:34:29 INFO - Running setup.py (path:/tmp/pip-j_6dKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:34:29 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)) 06:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:34:29 INFO - Running setup.py (path:/tmp/pip-LsyN_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:34:29 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)) 06:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:34:29 INFO - Running setup.py (path:/tmp/pip-8icGSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:34:29 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)) 06:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:34:29 INFO - Running setup.py (path:/tmp/pip-69uqLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:34:29 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)) 06:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:34:29 INFO - Running setup.py (path:/tmp/pip-mdWfSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:34:29 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)) 06:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:34:29 INFO - Running setup.py (path:/tmp/pip-We56Mq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:34:29 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)) 06:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:34:29 INFO - Running setup.py (path:/tmp/pip-Ud_x44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:34:29 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)) 06:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:34:29 INFO - Running setup.py (path:/tmp/pip-HdkLQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:34:29 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)) 06:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:34:29 INFO - Running setup.py (path:/tmp/pip-0HbCkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:34:29 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)) 06:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:34:29 INFO - Running setup.py (path:/tmp/pip-quaC8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:34:29 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)) 06:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:34:29 INFO - Running setup.py (path:/tmp/pip-ueDeYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:34:29 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)) 06:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:34:29 INFO - Running setup.py (path:/tmp/pip-o1nBTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:34:30 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)) 06:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:34:30 INFO - Running setup.py (path:/tmp/pip-uoQJaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:34:30 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)) 06:34:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:34:30 INFO - Running setup.py (path:/tmp/pip-f7d2GS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:34:30 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)) 06:34:30 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)) 06:34:30 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)) 06:34:30 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)) 06:34:30 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)) 06:34:30 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)) 06:34:30 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)) 06:34:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:34: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 06:34: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 06:34: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 06:34: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 06:34:31 INFO - Downloading blessings-1.6.tar.gz 06:34:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:34:31 INFO - Installing collected packages: blessings 06:34:31 INFO - Running setup.py install for blessings 06:34:31 INFO - Successfully installed blessings 06:34:31 INFO - Cleaning up... 06:34:31 INFO - Return code: 0 06:34:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:34:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:34:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:34:31 INFO - Reading from file tmpfile_stdout 06:34:31 INFO - Current package versions: 06:34:31 INFO - argparse == 1.2.1 06:34:31 INFO - blessings == 1.6 06:34:31 INFO - blobuploader == 1.2.4 06:34:31 INFO - docopt == 0.6.1 06:34:31 INFO - functools32 == 3.2.3-2 06:34:31 INFO - jsonschema == 2.5.1 06:34:31 INFO - manifestparser == 1.1 06:34:31 INFO - mozInstall == 1.13 06:34:31 INFO - mozcrash == 1.0 06:34:31 INFO - mozdebug == 0.1 06:34:31 INFO - mozdevice == 0.51 06:34:31 INFO - mozfile == 1.2 06:34:31 INFO - mozhttpd == 0.7 06:34:31 INFO - mozinfo == 0.10 06:34:31 INFO - mozleak == 0.1 06:34:31 INFO - mozlog == 3.5 06:34:31 INFO - moznetwork == 0.27 06:34:31 INFO - mozprocess == 0.25 06:34:31 INFO - mozprofile == 0.28 06:34:31 INFO - mozrunner == 6.13 06:34:31 INFO - mozscreenshot == 0.1 06:34:31 INFO - mozsystemmonitor == 0.3 06:34:31 INFO - moztest == 0.8 06:34:31 INFO - mozversion == 1.4 06:34:31 INFO - psutil == 3.1.1 06:34:31 INFO - requests == 1.2.3 06:34:31 INFO - wsgiref == 0.1.2 06:34:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:34: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')]} 06:34:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34: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')]} 06:34:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:31 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 0x26781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2840a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4580>, '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 0x29e5480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5910>, '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': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', '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 06:34:31 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 06:34:31 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 06:34:31 INFO - Using env: {'DISPLAY': ':0', 06:34:31 INFO - 'HOME': '/home/cltbld', 06:34:31 INFO - 'LANG': 'en_US.UTF-8', 06:34:31 INFO - 'LANGUAGE': 'en_US:en', 06:34:31 INFO - 'LOGNAME': 'cltbld', 06:34:31 INFO - 'MAIL': '/var/mail/cltbld', 06:34:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:31 INFO - 'MOZ_NO_REMOTE': '1', 06:34:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:31 INFO - 'NO_EM_RESTART': '1', 06:34:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:31 INFO - 'PWD': '/builds/slave/test', 06:34:31 INFO - 'SHELL': '/bin/bash', 06:34:31 INFO - 'SHLVL': '1', 06:34:31 INFO - 'TERM': 'linux', 06:34:31 INFO - 'TMOUT': '86400', 06:34:31 INFO - 'USER': 'cltbld', 06:34:31 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', 06:34:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:31 INFO - '_': '/tools/buildbot/bin/python'} 06:34:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:31 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)) 06:34:31 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)) 06:34:31 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)) 06:34:31 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)) 06:34:31 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)) 06:34:31 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)) 06:34:31 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)) 06:34:31 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)) 06:34:31 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)) 06:34:31 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 06:34: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 06:34: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 06:34: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 06:34: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 06:34:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 06:34:33 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)) 06:34:33 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)) 06:34:33 INFO - Installing collected packages: simplejson 06:34:33 INFO - Running setup.py install for simplejson 06:34:33 INFO - building 'simplejson._speedups' extension 06:34:33 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 06:34:34 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 06:34:34 INFO - Successfully installed simplejson 06:34:34 INFO - Cleaning up... 06:34:34 INFO - Return code: 0 06:34:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:34:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:34:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:34:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26781f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2840a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29e7b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29e4580>, '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 0x29e5480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5910>, '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': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', '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 06:34: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', 'jsonschema'] in /builds/slave/test/build 06:34: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 jsonschema 06:34:34 INFO - Using env: {'DISPLAY': ':0', 06:34:34 INFO - 'HOME': '/home/cltbld', 06:34:34 INFO - 'LANG': 'en_US.UTF-8', 06:34:34 INFO - 'LANGUAGE': 'en_US:en', 06:34:34 INFO - 'LOGNAME': 'cltbld', 06:34:34 INFO - 'MAIL': '/var/mail/cltbld', 06:34:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:34 INFO - 'MOZ_NO_REMOTE': '1', 06:34:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:34 INFO - 'NO_EM_RESTART': '1', 06:34:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:34 INFO - 'PWD': '/builds/slave/test', 06:34:34 INFO - 'SHELL': '/bin/bash', 06:34:34 INFO - 'SHLVL': '1', 06:34:34 INFO - 'TERM': 'linux', 06:34:34 INFO - 'TMOUT': '86400', 06:34:34 INFO - 'USER': 'cltbld', 06:34:34 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', 06:34:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:34 INFO - '_': '/tools/buildbot/bin/python'} 06:34:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:34:34 INFO - Cleaning up... 06:34:34 INFO - Return code: 0 06:34:34 INFO - Running post-action listener: _resource_record_post_action 06:34:34 INFO - Running post-action listener: _start_resource_monitoring 06:34:34 INFO - Starting resource monitoring. 06:34:34 INFO - [mozharness: 2017-08-31 13:34:34.427141Z] Finished create-virtualenv step (success) 06:34:34 INFO - [mozharness: 2017-08-31 13:34:34.427661Z] Running install step. 06:34:34 INFO - Running pre-action listener: _resource_record_pre_action 06:34:34 INFO - Running main action method: install 06:34:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:34:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:34:34 INFO - Reading from file tmpfile_stdout 06:34:34 INFO - Detecting whether we're running mozinstall >=1.0... 06:34:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:34:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:34:34 INFO - Reading from file tmpfile_stdout 06:34:34 INFO - Output received: 06:34:34 INFO - Usage: mozinstall [options] installer 06:34:34 INFO - Options: 06:34:34 INFO - -h, --help show this help message and exit 06:34:34 INFO - -d DEST, --destination=DEST 06:34:34 INFO - Directory to install application into. [default: 06:34:34 INFO - "/builds/slave/test"] 06:34:34 INFO - --app=APP Application being installed. [default: firefox] 06:34:34 INFO - mkdir: /builds/slave/test/build/application 06:34:34 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'] 06:34:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 06:34:51 INFO - Reading from file tmpfile_stdout 06:34:51 INFO - Output received: 06:34:51 INFO - /builds/slave/test/build/application/firefox/firefox 06:34:51 INFO - Running post-action listener: _resource_record_post_action 06:34:51 INFO - [mozharness: 2017-08-31 13:34:51.247227Z] Finished install step (success) 06:34:51 INFO - [mozharness: 2017-08-31 13:34:51.247562Z] Running setup-mitmproxy step. 06:34:51 INFO - Running pre-action listener: _resource_record_pre_action 06:34:51 INFO - Running main action method: setup_mitmproxy 06:34:51 INFO - Skipping: mitmproxy is not required 06:34:51 INFO - Running post-action listener: _resource_record_post_action 06:34:51 INFO - [mozharness: 2017-08-31 13:34:51.249175Z] Finished setup-mitmproxy step (success) 06:34:51 INFO - [mozharness: 2017-08-31 13:34:51.249489Z] Running run-tests step. 06:34:51 INFO - Running pre-action listener: _resource_record_pre_action 06:34:51 INFO - Running pre-action listener: _set_gcov_prefix 06:34:51 INFO - Running main action method: run_tests 06:34:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:34:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:34:51 INFO - Python 2.7.3 06:34:51 INFO - Return code: 0 06:34:51 INFO - grabbing minidump binary from tooltool 06:34:51 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')]} 06:34:51 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 0x29e4580>, '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 0x29e5480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29e5910>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:34:51 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 06:34:51 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 06:34:51 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 06:34:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:34:51 INFO - Return code: 0 06:34:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:34:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:34:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:34:51 INFO - ENV: RUST_BACKTRACE is now full 06:34:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:34:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:34:51 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/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 06:34:51 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/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-022 --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 06:34:51 INFO - Using env: {'DISPLAY': ':0', 06:34:51 INFO - 'HOME': '/home/cltbld', 06:34:51 INFO - 'LANG': 'en_US.UTF-8', 06:34:51 INFO - 'LANGUAGE': 'en_US:en', 06:34:51 INFO - 'LOGNAME': 'cltbld', 06:34:51 INFO - 'MAIL': '/var/mail/cltbld', 06:34:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:34:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:34:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:51 INFO - 'MOZ_NO_REMOTE': '1', 06:34:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:34:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:51 INFO - 'NO_EM_RESTART': '1', 06:34:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:51 INFO - 'PWD': '/builds/slave/test', 06:34:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:34:51 INFO - 'RUST_BACKTRACE': 'full', 06:34:51 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:34:51 INFO - 'SHELL': '/bin/bash', 06:34:51 INFO - 'SHLVL': '1', 06:34:51 INFO - 'STYLO_FORCE_ENABLED': '1', 06:34:51 INFO - 'STYLO_THREADS': '1', 06:34:51 INFO - 'TERM': 'linux', 06:34:51 INFO - 'TMOUT': '86400', 06:34:51 INFO - 'USER': 'cltbld', 06:34:51 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', 06:34:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:51 INFO - '_': '/tools/buildbot/bin/python'} 06:34:51 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/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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 06:34:51 INFO - mozversion application_buildid: 20170831123711 06:34:51 INFO - mozversion application_changeset: fb22415719a9d971a2646fa2d1b74e134ca00c3d 06:34:51 INFO - mozversion application_display_name: Nightly 06:34:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:34:51 INFO - mozversion application_name: Firefox 06:34:51 INFO - mozversion application_remotingname: firefox 06:34:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:34:51 INFO - mozversion application_vendor: Mozilla 06:34:51 INFO - mozversion application_version: 57.0a1 06:34:51 INFO - mozversion platform_buildid: 20170831123711 06:34:51 INFO - mozversion platform_changeset: fb22415719a9d971a2646fa2d1b74e134ca00c3d 06:34:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:34:51 INFO - mozversion platform_version: 57.0a1 06:34:51 INFO - using testdate: 1504186491 06:34:51 INFO - actual date: 1504186491 06:34:51 INFO - starting webserver on 'localhost:47489' 06:34:51 INFO - SUITE-START | Running 2 tests 06:34:51 INFO - TEST-START | dromaeo_css 06:34:51 INFO - Initialising browser for dromaeo_css test... 06:34:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47489/getInfo.html -profile /tmp/tmp10RF0b/profile 06:34:51 INFO - TEST-INFO | started process 8679 (/builds/slave/test/build/application/firefox/firefox http://localhost:47489/getInfo.html) 06:34:57 INFO - TEST-INFO | 8679: exit 0 06:34:58 INFO - Browser initialized. 06:34:58 INFO - Running cycle 1/1 for dromaeo_css test... 06:34:58 INFO - Using env: {'DISPLAY': ':0', 06:34:58 INFO - 'HOME': '/home/cltbld', 06:34:58 INFO - 'JSGC_DISABLE_POISONING': '1', 06:34:58 INFO - 'LANG': 'en_US.UTF-8', 06:34:58 INFO - 'LANGUAGE': 'en_US:en', 06:34:58 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 06:34:58 INFO - 'LOGNAME': 'cltbld', 06:34:58 INFO - 'MAIL': '/var/mail/cltbld', 06:34:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:34:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:34:58 INFO - 'MOZ_CRASHREPORTER': '1', 06:34:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:58 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 06:34:58 INFO - 'MOZ_NO_REMOTE': '1', 06:34:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:34:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:34:58 INFO - 'NO_EM_RESTART': '1', 06:34:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:34:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:34:58 INFO - 'PWD': '/builds/slave/test', 06:34:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:34:58 INFO - 'RUST_BACKTRACE': 'full', 06:34:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:34:58 INFO - 'SHELL': '/bin/bash', 06:34:58 INFO - 'SHLVL': '1', 06:34:58 INFO - 'STYLO_FORCE_ENABLED': '1', 06:34:58 INFO - 'STYLO_THREADS': '1', 06:34:58 INFO - 'TERM': 'linux', 06:34:58 INFO - 'TMOUT': '86400', 06:34:58 INFO - 'USER': 'cltbld', 06:34:58 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', 06:34:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:34:58 INFO - '_': '/tools/buildbot/bin/python'} 06:34:58 INFO - TEST-INFO | started process 8864 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp10RF0b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 06:34:59 INFO - PID 8864 | 06:34:59 INFO - PID 8864 | (/builds/slave/test/build/application/firefox/firefox:8915): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 06:34:59 INFO - PID 8864 | 06:35:09 INFO - PID 8864 | RSS: Main: 167354368 06:35:09 INFO - PID 8864 | 06:38:08 INFO - PID 8864 | Cycle 1(1): loaded http://localhost:47489/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47489/tests/dromaeo/cssquery-ext.html) 06:38:09 INFO - PID 8864 | RSS: Main: 173748224 06:38:09 INFO - PID 8864 | 06:41:11 INFO - PID 8864 | Cycle 1(1): loaded http://localhost:47489/tests/dromaeo/cssquery-ext.html (next: http://localhost:47489/tests/dromaeo/cssquery-jquery.html) 06:41:11 INFO - PID 8864 | RSS: Main: 169873408 06:41:11 INFO - PID 8864 | 06:44:13 INFO - PID 8864 | Cycle 1(1): loaded http://localhost:47489/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47489/tests/dromaeo/cssquery-mootools.html) 06:44:14 INFO - PID 8864 | RSS: Main: 172453888 06:44:14 INFO - PID 8864 | 06:47:15 INFO - PID 8864 | Cycle 1(1): loaded http://localhost:47489/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47489/tests/dromaeo/cssquery-prototype.html) 06:47:16 INFO - PID 8864 | RSS: Main: 170799104 06:47:16 INFO - PID 8864 | 06:50:20 INFO - PID 8864 | Cycle 1(1): loaded http://localhost:47489/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47489/tests/dromaeo/cssquery-yui.html) 06:50:20 INFO - PID 8864 | RSS: Main: 174297088 06:50:20 INFO - PID 8864 | 06:53:22 INFO - PID 8864 | Cycle 1(1): loaded http://localhost:47489/tests/dromaeo/cssquery-yui.html (next: http://localhost:47489/tests/dromaeo/cssquery-dojo.html) 06:53:22 INFO - PID 8864 | RSS: Main: 174833664 06:53:22 INFO - PID 8864 | 06:53:22 INFO - PID 8864 | __start_tp_report 06:53:22 INFO - PID 8864 | _x_x_mozilla_page_load 06:53:22 INFO - PID 8864 | _x_x_mozilla_page_load_details 06:53:22 INFO - PID 8864 | |i|pagename|runs| 06:53:22 INFO - PID 8864 | |0;dojo.html;3289.1448223461894;3296.9175770605734;3306.2395649000737;3328.251143492716;3335.8165300908454;6282.623042617446;6297.748090076398;6346.048092786084;6370.171877655904;6409.070684750708;6135;6215.937840621593;6237.251529816417;6278.058291256314;6287.0569414587835;5735.139729040657;5736.45503677151;5754.4533269339345;5780.682062486561;5882.029465138247;5797.913031304533;5821.796237131702;5833.416658334175;5913.704314784265;5931.6441013539325;5690.174924635905;5723.198752174699;5754.136879468071;5803.8258852234285;5888.440598143169;5716.714164291769;5814.447627475383;5853.239078919756;5905.763769449217;5908.675022873783;5762;5779.422057794229;5850.76596936122;5870.178175055496;5873.647581145145;6053.485453736438;6101.267847858286;6124.897518446678;6130.203073600448;6373.649449280288;6538.509611779135;6549.967250163766;6744.696488658022;6829;6831.726730930757;6731.528792984493;6744.494162937799;6748.561343512656;6751.561148525379;6780.457563394916;6193.318734939198;6239.594426362317;6248.718807653667;6284.308726040132;6309.842253943688;6750.392464678104;6867.965660171667;6885.965570172218;6951.200611929592;6951.2353641099435;6593.208814942238;6660.666966651745;6665.700043498055;6671.499637527205;6690.9294512877705;6777.49168812341;6813.420859226922;6856.348646878539;6861.416779573792;6864.931350686529;6713.597184168965;6723.63020033893;6793.864122717617;6804.115464989619;6808.93191068083;6519.967400162969;6616.4376028037195;6735.6295403753265;6741.629210393476;6816.931830681629;5260;5350.304460420199;5357.732113394315;5361.651492652965;5362.195670649356;4670.299455081697;4670.719756814534;4714.434267887876;4734.73958932262;4779;5842.38654941225;5898.14476900847;5935.910961335583;5938.28740551128;5973.313068997034;6631.535792494576;6632.303608121078;6692.698828552727;6705.027770973182;6823.658817059128;5765.106408506688;5832.766689332379;5916.763329466773;5953.196318497034;5978.671173085435;5812.564057695689;5856.267966504187;5885.323187833368;5892.882142357129;5896.380880007538;6502.60984340941;6551.868962620816;6660.333966603301;6682.565633233921;6698.933010669928;6581.638009909501;6703.865922681616;6762.661866906733;6789.422899053636;6863.210730766026;6466.612003279818;6478.028295755675;6496.415322620986;6504.284528701744;6539.803805885831;6523.869522609426;6544.3782840630065;6548.738050478118;6622.470202383702;6654.5341826073645;6670.299618540177;6674.43267322283;6775.322467753185;6777.728890844311;6778.8983165251575;6573.638449885302;6596.80209593713;6607.141071660845;6609;6611.140551728245;6348;6570.5400621956005;6643.2360278568585;6659.2008958925235;6660.700268488026;6290.937090629218;6415.903761443676;6418.871622567616;6419.518536109717;6433.035044743326;6396;6522.21733391996;6673;6678.966605166943;6763.6618169092335;6208.317085120723;6264.812055638338;6287.056941458635;6440.29156792743;6502.154719886385 06:53:22 INFO - PID 8864 | |1;ext.html;797.4617133434933;851.4508142248249;877.6971944679087;1142.337444282317;1160.7678464307141;29737;39500.60499395009;39913.20173596526;39976.600233997655;40001.1999760005;40467.59532404675;40861.38707919376;40888.38667419991;40938.18123637533;40971.385429218586;7597.962010189941;7755.806104847367;7756.224377562226;7762.30139287464;7768.262015108556;7533.434992375566;7593.316601505863;7693.192214817447;7694.846103077935;7695.692172313101;7697.499662521918;7726.459147859624;7744.109427415835;7748.070231572219;7750.031246094239;7596.696132154735;7662.195469475736;7691.153973062987;7694;7696.037995250594;7598.278163574451;7649.196834332371;7655.885161722578;7659.885101723423;7662.8084297893;38134;38188.23623527541;38201.23597528034;38204.80897595494;38299.808500957595;3556.875509357186;3580.5882323532865;3586.444101164324;3589.8025608591515;3591.2099338145567;30447.23881902948;30482.390352192833;30540.389192216033;30541.23646908845;30669.386612267852;4951.480094590088;5129.204973229155;5203.713795741232;5229.2156176573435;5231.058409486291;194363;196540;196687.99999999857;197401;197808;47701;48625;49467;49558;49577.25634115491;50218;50374.49625503772;50388.748056260214;50472.747636261585;50477.24284135741;12908.870911290955;12980.221186728826;12988.155769874928;13010.349482525837;14507.129572225489;11664.591739289084;11711.1802173847;11718;11764.058875289957;11766.941165294118;12389.814152787716;12703.809442858364;14494.202818844853;14776.926115369355;14797.926010370094;5873.236479257755;5955;5958.165856780054;5990.341062483123;5999.610025348338;1013.0983424751977;1018.709667744704;1025.4513835098187;1031.1802117316602;1051.553089936789;22541.05531532008;22629.660555091687;22654;22986.310410687707;23033.424164396023;1102.1403305421784;1114.9832752508719;1132.7224829916722;1133.5692436873935;1136.9260998035102;12341.321227332402;12389.946854517286;12394.070444716683;12426.440810163564;12453.718950530438;240119;242689;242835;242884;244097;50436.74781626142;50764;50935;50958.235626465634;51347.48652513427;1396.1762560089596;1442.9899070650376;1447.2488289170012;1450.840407555168;1467.288365142905;22379.328620141423;22481.325560233217;22585.322440326818;22639.320820375415;22666.546669066855;36938.076548086516;36940.076498087765;37034.444483332234;37035;37072.258554828215;197208;197558;198157;198162;198592;34620.13449663779;34633.134171645914;34894;34898.47652285269;34913;40844.1831163381;40871.18257634771;42381.36427953521;45014.549854501034;46922.765386172854;33344.333113338085;33475.163120922174;33589.3282134351;33979.830100850326;34303.31393372069;34007;34415.82792086023;34549.13627159341;34593;34692.132696682784;38468.6153138465;38893.2221355577;39075.80462097671;39259.82220533388;39277;33491;33697.663023370435;33776.49335259924;33900.49149262714;34152;24383.39041523905;24428.877855610608;24527.264182074567;24857.1300004499;25276.62085068762 06:53:22 INFO - PID 8864 | |2;jquery.html;571.1974675580806;579.2787978966188;579.5566391710342;590.4538302070584;592.2922108080844;21540.676889846643;22258.220962266325;22311.776882231174;22342.329730108082;22365.664515032287;24972.25083247501;25287;25365.239042828744;25388;25502.74497255027;6461.450776683988;6628.701708423108;6653.933460665404;6654.733810647568;6655.201375834882;6492.156019717454;6538.738450461976;6540.313267106957;6544.083828264047;6680.131582894242;6968.930310696905;6974.128233970754;6974.581525108484;6977.79066628002;6983.82540436488;6962.373386395224;6966.268541803089;6969.512134150612;6969.825754356157;6970.965145174242;6814.62519561424;6815.659217039129;6822.147231596051;6822.624755638438;6848.246692863781;30316.24209394783;30484;31014.844925775225;31137.377252454826;31153.376932461222;2059.227789578908;2094.98952505238;2095.0886364431367;2104.5790841831695;2107.947301317464;20135.39593812188;20756.065977030918;20777.480562985897;20845.58308833815;20852.47868803277;2358.7995020423114;2360.409897525619;2362.6503867606457;2387.4866903615807;2446.2661201639617;138190.30904845512;139783.30108349494;139875.30062349726;140505;140611;19723.56219850522;21190.78809211919;21215.8939205303;21440.78559214388;21978.230761923576;42814.357784633256;42921.785391072845;43042.569574304485;43071;43144.78427607842;10940.56237750497;11019;11029;11131.888681113247;11180.21738478315;9073.364864459556;9081.954590227007;9162.267018638493;9176.17414432691;9219.078092190728;8458.915410845935;9180.21968132703;9275.76810579741;9303.302252331103;10990.560377584807;2562.2981828344823;2648.3379155211196;2655.7344265573674;2687.9328016799345;2694.2321438389963;963.556763888605;980.3872579637726;994.3089552760762;999.3853779925294;1006.833872411044;19426.514337141685;19685.212591496467;19710.310139145062;20243.898780506002;20430.08064637095;1025.620520407439;1046.931949423285;1055.472263868066;1058.1270451877233;1060.1201003167353;10840.945795270973;13015.544455943997;13248.40382182804;14218.573442796733;14554.781678274834;463881;476027;487064;488181;502147;117546;117565;118982.40508797401;119109;122066.38966805287;1213.8546862464761;1216.397883047897;1234.6481252842896;1239.9442025108892;1248.0514808745238;20815.479613009797;20921;21213.681794773398;21246;21556.814375209513;55282.447175527726;58077.709611451675;58596.70701646464;58838.41161588329;60560.697196515495;74004.62997684977;75020.62489687734;75075.62462187654;75305;75544.24455755373;14037.859621403655;14124.717505650035;14198.148111113367;14282.714345713235;14310.856891430953;18015.459536213933;18021.2791488339;18058.36795712196;18072.457826265232;22528.32415027552;17097.82902171012;17201.225944832262;17214.569635759206;17232.827671723124;17459.912700436926;15743.763843542585;16856.66286674284;17693.292268309637;17845.910770446066;18104.637907242046;17997.640047199246;19493.8050619492;19496.805031949498;19658.901705491382;19907.800921991173;14550.12699238049;14696.265186740406;14813;14820.110793352433;14843.703125937636;18341.54146146357;18660.160292787128;18665.533361665523;18683.252669893594;19301.4209573713 06:53:22 INFO - PID 8864 | |3;mootools.html;1310.4561606933123;1367.8905687544998;1371.3417559571403;1375.669839238583;1377.8208832851728;12597.496100155982;14819.18494482803;15514.767278490832;15564.299606517692;15615.063096214235;12914.418851151697;15077.396904123849;15517.767233491506;15519.767203491956;15587;2709.1872438268488;2769.9584506232422;2770.8476033818133;2772.099067802972;2773.251222170013;2621.934451638705;2700.1224602004327;2703.3106557827728;2795.9860200698968;2812.9718702812916;2773.320536468568;2801.831890086591;2801.957970630442;2802.1733588591314;2802.383475635357;2674.7993900457445;2690.8250963687296;2694.1648089092446;2699.095802906019;2700.7299270072936;2673.4920365130565;2708.295843080813;2709.498742732601;2722.6250743374667;2724.8910043598235;14925;15278.541643750705;15280.465183718516;15287.770683439758;15290.311935962909;8010.198980102002;8370.204830541088;8372.16278372164;8373.911391519125;8381.664733410656;5208.5572726318305;5390.434004429557;5477.698726570037;5508.063629183049;5556.777728890839;1781.4655603318952;1833.2758560368713;1851.527860395604;1879.2201236486978;1894.5358387195226;25355.366115847068;25501;25572.744272557407;25574.23277301684;25593.36016599581;6564.901526477011;6729.25978142404;6950.617716025566;6951.443884489228;7036.683349249296;16723.581910452336;17242.827571724374;17315.56711082233;17338.91330543339;17565.560860978323;7897.1707970662255;8154.8776768348525;8191.549464779375;8192;8195.385346098947;7442.06974128234;7496.100467943882;7526;7529.924700752922;7548.433867459962;5228.843134705965;5262.236975638511;5263.526282634582;5294.047071527162;5406.513413792777;1483.050847457628;1489.3744627256578;1490.8509149085005;1493.9103653780592;1504.7441934870878;855.619249433996;879.520661239626;887.0154128916897;887.7159309021051;898.766320756595;7691.961540192375;7713.65288562016;7730.497517661334;7762.6894924203525;7852.057753069668;707.8124297061283;717.5658726470515;737.4653376302246;737.5021860244314;746.0227102495747;5155.819546315936;5182.585393168538;5409.080456322435;5631.8592035199445;5660.462256085665;19985.30051448192;21144.577108457746;21150.048247828883;22024.44938876509;22082;21279.361619151452;21371.67942480864;21376;21378.572428551342;21536.246231381825;10592.364458132537;11297.300530324137;11603.07175425964;11603.941980289876;11610.187286890006;8393.958030210055;8544.786380340542;8554.486730795923;8677.479351238946;8750.212480876751;18407.263709452007;18566.443006709822;18662.72005919885;18718.438446846616;18864.33974810875;18731.344402945833;18967.241310347432;19130.80869191346;19284.903575482593;19291.51771205731;11623.88376116228;11941.104417168573;11946.76106477883;11951.22317049389;11951.402429878644;13747.587572372844;14058.859411405754;14077.788833167715;14106.929465352607;14108.153510789385;10628.89371106279;10690.347690452118;10711.25021248505;10882.727931801768;11040;10331.276810623183;10602.840957385799;10679.626213082505;10746.892531074589;10824.296420732628;8810.603522841615;8855.247303978977;8888.6000129993;9004.234640055669;9016;6674.466042716668;7321.853562928605;7327.303906128909;7338.229485903903;7349.6692648832;7329.963350183215;7355.301246381585;7374.631268436449;7454.813629659302;7459.291367320096 06:53:22 INFO - PID 8864 | |4;prototype.html;500.81893354441405;500.95741861941735;527.2986927386576;528.9233061206123;537.7768226186133;6191.876162476748;6228.968855155724;6238.656873871935;6250.968745156273;6268.905966410502;5904.498117659993;6132.417420345071;6140.140380346755;6150.415710507495;6169.845753856144;5111.361079865017;5171.19846423803;5190.896182076356;5222.477752224766;5235.816746413876;5045.520675535819;5127.154019586764;5134.460881607433;5152.201408781645;5203.543007957759;5102.438731739506;5162.199859021857;5195.558377537895;5197.194434862602;5239.187925871496;5133.178691409394;5144.4598317176715;5150.768215430277;5161.948380516222;5178.922316165279;4830.372051633265;4842.685225460334;4847.854564363075;4859.173940430135;4876.390451193601;6069.119977603223;6150.200473938404;6171.722272497748;6175.197224360849;6250.749970001195;5966.522678185735;5988;5992.543057346467;6029.60807547508;6054.334023257438;6443.967780161116;6465.935340646627;6497.967510162466;6572.342765723437;6588.242352129519;5146.176611742103;5180.870478238036;5242.501962313574;5288.418273989858;5298.6555873868465;6833.145856767904;6886.24251332353;6942.479314051416;6986.61573613451;7005.54463959841;5266.368035835724;5272.710000949908;5312.229726689686;5325.680459172462;5355.4376790436445;5397.271368365299;5414.539764120015;5435.782568697208;5437.45625437461;5450.672959622435;5920.5559583031445;5923.733431995571;5937.614055086406;5941.1385349125;5997.55018373615;6401.487880969511;6445.226572811199;6566.409023187936;6617.53677242598;6635.279535390467;1744.6597913406763;1786.240847639748;1824.6806808808406;1875.8499320054636;1899.990500047491;1360.7482615716124;1371.3143428285857;1383.2876068824564;1385.5773988933472;1403.0669604712753;5596.916046259309;5681.34664513578;5705.177531054203;5711.286089238845;5776.480116789425;6372.4583410409705;6420.454261387741;6554.80335589933;6556.377144171296;6657.800265992028;5728.283964504437;5735.111057785966;5785.82642520725;5797.217375654317;5845.064789633639;3533.5759708835103;3564.2891412122326;3565.484669015658;3592.1558433768046;3594.51474051005;6568.211814582281;6586.4401525870835;6588.53880228389;6589.209294884548;6624.536282460278;6916.273791251947;6921.082474484244;6938.548994315353;6941.965290173618;6945.40964018054;6536.640484773193;6539.738410463623;6564.67176641166;6574.375434333826;6579;6626.6686665667485;6683.429394092287;6690.531662783558;6708.731650733916;6748.6963086660235;6589.604623722592;6622.90065649006;6661.400473957173;6673.399394054559;6677.933220667731;6524.575902566318;6525.314841941527;6538.215414150332;6565.507586930904;6572.572782769104;4938.629602779734;4940.888300132441;4941.925871112008;4955.2567114933045;4970.676906001243;6597;6617.536772425884;6649.240565165403;6693.531452798257;6709.85284708908;6139.72371243304;6158.168647232566;6317.6209427434505;6339.714712837841;6422.486201103994;6375.776847810304;6471.546991710535;6478.384553467413;6482;6492;6359.395857393725;6362.300146983735;6376.266729326187;6385.68071596409;6410.775622853178;6350.872982540231;6371.776987805404;6456.192975878015;6517.8696426072165;6563.619735835953;6275.780347687809;6294.527910406646;6296.937030629819;6332.556721029484;6366.3633636637815 06:53:22 INFO - PID 8864 | |5;yui.html;902.6344330546127;915.6154415145638;920.5535315372039;925.3846192282133;937.756183392318;17849.910750446244;18771.343002994898;18796.71804922924;18835.99999999998;21144.4713882153;20461.693074603892;21317.893410532964;21401.143954241852;21597.244096456627;21626.783732162672;946.2713710442972;955.6416343871049;959.491469521155;965.3339195954804;966.6809952715587;941.7975135345899;956.689076050283;957.760559860035;967.3808762392075;976.414151509096;1054.978900421991;1055.8996895294936;1056.7410984308856;1059.6926891201542;1060.3107979813105;1039.7244730146517;1041.6406339812752;1050.4905121016302;1051.55834549489;1052.5947510208587;763.065245074779;765.1736124985002;765.3800421658475;766.8427972265672;772.2200577416793;18318;18552.814471855378;18601.81398186028;18629;18849.340273090376;20412.387628371172;20513.692294615594;20890.895545522326;20943.581128377347;21519.246826361003;16806.663866722596;16929.492115236564;17267.3956411525;17418.912905435518;17432.21555030027;3745.045013521562;3755.9812200939095;3764.8117594120185;3776.8300426480873;3779.79211143387;112716.4364178182;114271.42864285625;114410;117420.41289793582;119147.40426297899;15883.205839707969;16392.918035409748;16456.753148702777;16465.25906334218;16476.917615411847;29133.56299655507;30672.386552269276;30896.53655195174;30907.536386954213;30927.381452370828;8961.910380896108;8973.237274831712;9221.631134754669;9249.95375023134;9253.537323133816;4264.893377665521;4276.657867370603;4378.890527736832;4383.956160438354;4519;5242.030224408497;5363.5440987516495;5371.274877891435;5371.677699338053;5372.059889519319;1066.7013236293851;1080.0657431322006;1102.3551222534907;1104.9723756906142;1105.093823065095;668.9498287628447;673.1383828699272;683.9829004274833;688.7795905310353;696.6864910790165;11325.603603873826;11736.295822250693;11858.110641701907;12223.93888030554;12477.313747743961;681.7273090763734;693.9722411103601;702.3889216381781;709.670003448399;711.2212127720138;5351.143816989341;5422.945770542323;5436.10304299794;5969.3732158122775;6272.529560282997;107644.4085200657;108375;108820.4558977216;111402;112215.4389228065;92660.53669731609;93630;95071;97337;98858.50570747245;84170;85812;86044.56977715071;89572;90134.54932725294;12681.30252836103;12798;12864.742705145658;12870.742585148057;12876.484940602271;39602.405963911144;39738;39832;40039;41813;51405.742971284904;52983.73508132435;54009.18986215288;54157.72921135527;54986;16158;16591.585210369838;17076.573085672957;17107.828921710625;17166.570835729206;18562.628747424707;19930.60138797187;20023;20031.599368012266;20316.288929887975;16956.152192390084;17627.206775694867;17649.205785739417;18222;18646.81353186505;17288.827111728722;17672;17733.46799596014;17999.820001800337;18066.638667226845;16818.327266909677;17475.56311092233;17545;17661.64676706433;17724.291028358723;11703.356315402734;11724.531018759155;11958.162928594915;12295.754084918073;12616.621501354974;13070.281134537692;13182.868171318163;13244.072914896251;13471.865281347453;13515.932420337835 06:53:22 INFO - PID 8864 | __end_tp_report 06:53:22 INFO - PID 8864 | __start_cc_report 06:53:22 INFO - PID 8864 | _x_x_mozilla_cycle_collect,361 06:53:22 INFO - PID 8864 | __end_cc_report 06:53:22 INFO - PID 8864 | __startTimestamp1504187602725__endTimestamp 06:53:22 INFO - PID 8864 | 06:53:22 INFO - PID 8864 | ------- Summary: start ------- 06:53:22 INFO - PID 8864 | Number of tests: 6 06:53:22 INFO - PID 8864 | 06:53:22 INFO - PID 8864 | [#0] dojo.html Cycles:165 Average:6204.94 Median:6418.87 stddev:708.36 (11.0%) stddev-sans-first:672.59 06:53:22 INFO - PID 8864 | Values: 3289.1 3296.9 3306.2 3328.3 3335.8 6282.6 6297.7 6346.0 6370.2 6409.1 6135.0 6215.9 6237.3 6278.1 6287.1 5735.1 5736.5 5754.5 5780.7 5882.0 5797.9 5821.8 5833.4 5913.7 5931.6 5690.2 5723.2 5754.1 5803.8 5888.4 5716.7 5814.4 5853.2 5905.8 5908.7 5762.0 5779.4 5850.8 5870.2 5873.6 6053.5 6101.3 6124.9 6130.2 6373.6 6538.5 6550.0 6744.7 6829.0 6831.7 6731.5 6744.5 6748.6 6751.6 6780.5 6193.3 6239.6 6248.7 6284.3 6309.8 6750.4 6868.0 6886.0 6951.2 6951.2 6593.2 6660.7 6665.7 6671.5 6690.9 6777.5 6813.4 6856.3 6861.4 6864.9 6713.6 6723.6 6793.9 6804.1 6808.9 6520.0 6616.4 6735.6 6741.6 6816.9 5260.0 5350.3 5357.7 5361.7 5362.2 4670.3 4670.7 4714.4 4734.7 4779.0 5842.4 5898.1 5935.9 5938.3 5973.3 6631.5 6632.3 6692.7 6705.0 6823.7 5765.1 5832.8 5916.8 5953.2 5978.7 5812.6 5856.3 5885.3 5892.9 5896.4 6502.6 6551.9 6660.3 6682.6 6698.9 6581.6 6703.9 6762.7 6789.4 6863.2 6466.6 6478.0 6496.4 6504.3 6539.8 6523.9 6544.4 6548.7 6622.5 6654.5 6670.3 6674.4 6775.3 6777.7 6778.9 6573.6 6596.8 6607.1 6609.0 6611.1 6348.0 6570.5 6643.2 6659.2 6660.7 6290.9 6415.9 6418.9 6419.5 6433.0 6396.0 6522.2 6673.0 6679.0 6763.7 6208.3 6264.8 6287.1 6440.3 6502.2 06:53:22 INFO - PID 8864 | 06:53:22 INFO - PID 8864 | [#1] ext.html Cycles:180 Average:38043.78 Median:34896.24 stddev:55350.73 (158.6%) stddev-sans-first:55435.35 06:53:22 INFO - PID 8864 | Values: 797.5 851.5 877.7 1142.3 1160.8 29737.0 39500.6 39913.2 39976.6 40001.2 40467.6 40861.4 40888.4 40938.2 40971.4 7598.0 7755.8 7756.2 7762.3 7768.3 7533.4 7593.3 7693.2 7694.8 7695.7 7697.5 7726.5 7744.1 7748.1 7750.0 7596.7 7662.2 7691.2 7694.0 7696.0 7598.3 7649.2 7655.9 7659.9 7662.8 38134.0 38188.2 38201.2 38204.8 38299.8 3556.9 3580.6 3586.4 3589.8 3591.2 30447.2 30482.4 30540.4 30541.2 30669.4 4951.5 5129.2 5203.7 5229.2 5231.1 194363.0 196540.0 196688.0 197401.0 197808.0 47701.0 48625.0 49467.0 49558.0 49577.3 50218.0 50374.5 50388.7 50472.7 50477.2 12908.9 12980.2 12988.2 13010.3 14507.1 11664.6 11711.2 11718.0 11764.1 11766.9 12389.8 12703.8 14494.2 14776.9 14797.9 5873.2 5955.0 5958.2 5990.3 5999.6 1013.1 1018.7 1025.5 1031.2 1051.6 22541.1 22629.7 22654.0 22986.3 23033.4 1102.1 1115.0 1132.7 1133.6 1136.9 12341.3 12389.9 12394.1 12426.4 12453.7 240119.0 242689.0 242835.0 242884.0 244097.0 50436.7 50764.0 50935.0 50958.2 51347.5 1396.2 1443.0 1447.2 1450.8 1467.3 22379.3 22481.3 22585.3 22639.3 22666.5 36938.1 36940.1 37034.4 37035.0 37072.3 197208.0 197558.0 198157.0 198162.0 198592.0 34620.1 34633.1 34894.0 34898.5 34913.0 40844.2 40871.2 42381.4 45014.5 46922.8 33344.3 33475.2 33589.3 33979.8 34303.3 34007.0 34415.8 34549.1 34593.0 34692.1 38468.6 38893.2 39075.8 39259.8 39277.0 33491.0 33697.7 33776.5 33900.5 34152.0 24383.4 24428.9 24527.3 24857.1 25276.6 06:53:22 INFO - PID 8864 | 06:53:22 INFO - PID 8864 | [#2] jquery.html Cycles:180 Average:35773.02 Median:21767.52 stddev:81713.69 (375.4%) stddev-sans-first:81900.18 06:53:22 INFO - PID 8864 | Values: 571.2 579.3 579.6 590.5 592.3 21540.7 22258.2 22311.8 22342.3 22365.7 24972.3 25287.0 25365.2 25388.0 25502.7 6461.5 6628.7 6653.9 6654.7 6655.2 6492.2 6538.7 6540.3 6544.1 6680.1 6968.9 6974.1 6974.6 6977.8 6983.8 6962.4 6966.3 6969.5 6969.8 6971.0 6814.6 6815.7 6822.1 6822.6 6848.2 30316.2 30484.0 31014.8 31137.4 31153.4 2059.2 2095.0 2095.1 2104.6 2107.9 20135.4 20756.1 20777.5 20845.6 20852.5 2358.8 2360.4 2362.7 2387.5 2446.3 138190.3 139783.3 139875.3 140505.0 140611.0 19723.6 21190.8 21215.9 21440.8 21978.2 42814.4 42921.8 43042.6 43071.0 43144.8 10940.6 11019.0 11029.0 11131.9 11180.2 9073.4 9082.0 9162.3 9176.2 9219.1 8458.9 9180.2 9275.8 9303.3 10990.6 2562.3 2648.3 2655.7 2687.9 2694.2 963.6 980.4 994.3 999.4 1006.8 19426.5 19685.2 19710.3 20243.9 20430.1 1025.6 1046.9 1055.5 1058.1 1060.1 10840.9 13015.5 13248.4 14218.6 14554.8 463881.0 476027.0 487064.0 488181.0 502147.0 117546.0 117565.0 118982.4 119109.0 122066.4 1213.9 1216.4 1234.6 1239.9 1248.1 20815.5 20921.0 21213.7 21246.0 21556.8 55282.4 58077.7 58596.7 58838.4 60560.7 74004.6 75020.6 75075.6 75305.0 75544.2 14037.9 14124.7 14198.1 14282.7 14310.9 18015.5 18021.3 18058.4 18072.5 22528.3 17097.8 17201.2 17214.6 17232.8 17459.9 15743.8 16856.7 17693.3 17845.9 18104.6 17997.6 19493.8 19496.8 19658.9 19907.8 14550.1 14696.3 14813.0 14820.1 14843.7 18341.5 18660.2 18665.5 18683.3 19301.4 06:53:22 INFO - PID 8864 | 06:53:22 INFO - PID 8864 | [#3] mootools.html Cycles:180 Average:9264.65 Median:2699.61 stddev:6568.41 (243.3%) stddev-sans-first:6559.64 06:53:22 INFO - PID 8864 | Values: 1310.5 1367.9 1371.3 1375.7 1377.8 12597.5 14819.2 15514.8 15564.3 15615.1 12914.4 15077.4 15517.8 15519.8 15587.0 2709.2 2770.0 2770.8 2772.1 2773.3 2621.9 2700.1 2703.3 2796.0 2813.0 2773.3 2801.8 2802.0 2802.2 2802.4 2674.8 2690.8 2694.2 2699.1 2700.7 2673.5 2708.3 2709.5 2722.6 2724.9 14925.0 15278.5 15280.5 15287.8 15290.3 8010.2 8370.2 8372.2 8373.9 8381.7 5208.6 5390.4 5477.7 5508.1 5556.8 1781.5 1833.3 1851.5 1879.2 1894.5 25355.4 25501.0 25572.7 25574.2 25593.4 6564.9 6729.3 6950.6 6951.4 7036.7 16723.6 17242.8 17315.6 17338.9 17565.6 7897.2 8154.9 8191.5 8192.0 8195.4 7442.1 7496.1 7526.0 7529.9 7548.4 5228.8 5262.2 5263.5 5294.0 5406.5 1483.1 1489.4 1490.9 1493.9 1504.7 855.6 879.5 887.0 887.7 898.8 7692.0 7713.7 7730.5 7762.7 7852.1 707.8 717.6 737.5 737.5 746.0 5155.8 5182.6 5409.1 5631.9 5660.5 19985.3 21144.6 21150.0 22024.4 22082.0 21279.4 21371.7 21376.0 21378.6 21536.2 10592.4 11297.3 11603.1 11603.9 11610.2 8394.0 8544.8 8554.5 8677.5 8750.2 18407.3 18566.4 18662.7 18718.4 18864.3 18731.3 18967.2 19130.8 19284.9 19291.5 11623.9 11941.1 11946.8 11951.2 11951.4 13747.6 14058.9 14077.8 14106.9 14108.2 10628.9 10690.3 10711.3 10882.7 11040.0 10331.3 10602.8 10679.6 10746.9 10824.3 8810.6 8855.2 8888.6 9004.2 9016.0 6674.5 7321.9 7327.3 7338.2 7349.7 7330.0 7355.3 7374.6 7454.8 7459.3 06:53:22 INFO - PID 8864 | 06:53:22 INFO - PID 8864 | [#4] prototype.html Cycles:180 Average:5572.88 Median:6150.31 stddev:1500.95 (24.4%) stddev-sans-first:1456.08 06:53:22 INFO - PID 8864 | Values: 500.8 501.0 527.3 528.9 537.8 6191.9 6229.0 6238.7 6251.0 6268.9 5904.5 6132.4 6140.1 6150.4 6169.8 5111.4 5171.2 5190.9 5222.5 5235.8 5045.5 5127.2 5134.5 5152.2 5203.5 5102.4 5162.2 5195.6 5197.2 5239.2 5133.2 5144.5 5150.8 5161.9 5178.9 4830.4 4842.7 4847.9 4859.2 4876.4 6069.1 6150.2 6171.7 6175.2 6250.7 5966.5 5988.0 5992.5 6029.6 6054.3 6444.0 6465.9 6498.0 6572.3 6588.2 5146.2 5180.9 5242.5 5288.4 5298.7 6833.1 6886.2 6942.5 6986.6 7005.5 5266.4 5272.7 5312.2 5325.7 5355.4 5397.3 5414.5 5435.8 5437.5 5450.7 5920.6 5923.7 5937.6 5941.1 5997.6 6401.5 6445.2 6566.4 6617.5 6635.3 1744.7 1786.2 1824.7 1875.8 1900.0 1360.7 1371.3 1383.3 1385.6 1403.1 5596.9 5681.3 5705.2 5711.3 5776.5 6372.5 6420.5 6554.8 6556.4 6657.8 5728.3 5735.1 5785.8 5797.2 5845.1 3533.6 3564.3 3565.5 3592.2 3594.5 6568.2 6586.4 6588.5 6589.2 6624.5 6916.3 6921.1 6938.5 6942.0 6945.4 6536.6 6539.7 6564.7 6574.4 6579.0 6626.7 6683.4 6690.5 6708.7 6748.7 6589.6 6622.9 6661.4 6673.4 6677.9 6524.6 6525.3 6538.2 6565.5 6572.6 4938.6 4940.9 4941.9 4955.3 4970.7 6597.0 6617.5 6649.2 6693.5 6709.9 6139.7 6158.2 6317.6 6339.7 6422.5 6375.8 6471.5 6478.4 6482.0 6492.0 6359.4 6362.3 6376.3 6385.7 6410.8 6350.9 6371.8 6456.2 6517.9 6563.6 6275.8 6294.5 6296.9 6332.6 6366.4 06:53:22 INFO - PID 8864 | 06:53:22 INFO - PID 8864 | [#5] yui.html Cycles:180 Average:22824.05 Median:20487.69 stddev:30533.74 (149.0%) stddev-sans-first:30575.02 06:53:22 INFO - PID 8864 | Values: 902.6 915.6 920.6 925.4 937.8 17849.9 18771.3 18796.7 18836.0 21144.5 20461.7 21317.9 21401.1 21597.2 21626.8 946.3 955.6 959.5 965.3 966.7 941.8 956.7 957.8 967.4 976.4 1055.0 1055.9 1056.7 1059.7 1060.3 1039.7 1041.6 1050.5 1051.6 1052.6 763.1 765.2 765.4 766.8 772.2 18318.0 18552.8 18601.8 18629.0 18849.3 20412.4 20513.7 20890.9 20943.6 21519.2 16806.7 16929.5 17267.4 17418.9 17432.2 3745.0 3756.0 3764.8 3776.8 3779.8 112716.4 114271.4 114410.0 117420.4 119147.4 15883.2 16392.9 16456.8 16465.3 16476.9 29133.6 30672.4 30896.5 30907.5 30927.4 8961.9 8973.2 9221.6 9250.0 9253.5 4264.9 4276.7 4378.9 4384.0 4519.0 5242.0 5363.5 5371.3 5371.7 5372.1 1066.7 1080.1 1102.4 1105.0 1105.1 668.9 673.1 684.0 688.8 696.7 11325.6 11736.3 11858.1 12223.9 12477.3 681.7 694.0 702.4 709.7 711.2 5351.1 5422.9 5436.1 5969.4 6272.5 107644.4 108375.0 108820.5 111402.0 112215.4 92660.5 93630.0 95071.0 97337.0 98858.5 84170.0 85812.0 86044.6 89572.0 90134.5 12681.3 12798.0 12864.7 12870.7 12876.5 39602.4 39738.0 39832.0 40039.0 41813.0 51405.7 52983.7 54009.2 54157.7 54986.0 16158.0 16591.6 17076.6 17107.8 17166.6 18562.6 19930.6 20023.0 20031.6 20316.3 16956.2 17627.2 17649.2 18222.0 18646.8 17288.8 17672.0 17733.5 17999.8 18066.6 16818.3 17475.6 17545.0 17661.6 17724.3 11703.4 11724.5 11958.2 12295.8 12616.6 13070.3 13182.9 13244.1 13471.9 13515.9 06:53:22 INFO - PID 8864 | -------- Summary: end -------- 06:53:22 INFO - PID 8864 | 06:53:23 INFO - TEST-INFO | 8864: exit 0 06:53:23 INFO - TEST-OK | dromaeo_css | took 1111891ms 06:53:23 INFO - TEST-START | kraken 06:53:23 INFO - Initialising browser for kraken test... 06:53:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47489/getInfo.html -profile /tmp/tmpEeswQ1/profile 06:53:23 INFO - TEST-INFO | started process 9243 (/builds/slave/test/build/application/firefox/firefox http://localhost:47489/getInfo.html) 06:53:29 INFO - TEST-INFO | 9243: exit 0 06:53:29 INFO - Browser initialized. 06:53:29 INFO - Running cycle 1/1 for kraken test... 06:53:29 INFO - Using env: {'DISPLAY': ':0', 06:53:29 INFO - 'HOME': '/home/cltbld', 06:53:29 INFO - 'JSGC_DISABLE_POISONING': '1', 06:53:29 INFO - 'LANG': 'en_US.UTF-8', 06:53:29 INFO - 'LANGUAGE': 'en_US:en', 06:53:29 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 06:53:29 INFO - 'LOGNAME': 'cltbld', 06:53:29 INFO - 'MAIL': '/var/mail/cltbld', 06:53:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:53:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:53:29 INFO - 'MOZ_CRASHREPORTER': '1', 06:53:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:53:29 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 06:53:29 INFO - 'MOZ_NO_REMOTE': '1', 06:53:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:53:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:53:29 INFO - 'NO_EM_RESTART': '1', 06:53:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:53:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:53:29 INFO - 'PWD': '/builds/slave/test', 06:53:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:53:29 INFO - 'RUST_BACKTRACE': 'full', 06:53:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 06:53:29 INFO - 'SHELL': '/bin/bash', 06:53:29 INFO - 'SHLVL': '1', 06:53:29 INFO - 'STYLO_FORCE_ENABLED': '1', 06:53:29 INFO - 'STYLO_THREADS': '1', 06:53:29 INFO - 'TERM': 'linux', 06:53:29 INFO - 'TMOUT': '86400', 06:53:29 INFO - 'USER': 'cltbld', 06:53:29 INFO - 'XDG_SESSION_COOKIE': '930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862', 06:53:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:53:29 INFO - '_': '/tools/buildbot/bin/python'} 06:53:29 INFO - TEST-INFO | started process 9426 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEeswQ1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 06:53:30 INFO - PID 9426 | 06:53:30 INFO - PID 9426 | (/builds/slave/test/build/application/firefox/firefox:9477): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 06:53:30 INFO - PID 9426 | 06:53:40 INFO - PID 9426 | RSS: Main: 173076480 06:53:40 INFO - PID 9426 | 06:53:43 INFO - PID 9426 | Cycle 1(1): loaded http://localhost:47489/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47489/tests/kraken/driver.html?testName=audio-beat-detection) 06:53:44 INFO - PID 9426 | RSS: Main: 178286592 06:53:44 INFO - PID 9426 | 06:53:46 INFO - PID 9426 | Cycle 1(1): loaded http://localhost:47489/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47489/tests/kraken/driver.html?testName=audio-dft) 06:53:47 INFO - PID 9426 | RSS: Main: 178511872 06:53:47 INFO - PID 9426 | 06:53:50 INFO - PID 9426 | Cycle 1(1): loaded http://localhost:47489/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47489/tests/kraken/driver.html?testName=audio-fft) 06:53:51 INFO - PID 9426 | RSS: Main: 172806144 06:53:51 INFO - PID 9426 | 06:53:53 INFO - PID 9426 | Cycle 1(1): loaded http://localhost:47489/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47489/tests/kraken/driver.html?testName=audio-oscillator) 06:53:53 INFO - PID 9426 | RSS: Main: 173232128 06:53:53 INFO - PID 9426 | 06:53:56 INFO - PID 9426 | Cycle 1(1): loaded http://localhost:47489/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47489/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:53:56 INFO - PID 9426 | RSS: Main: 173608960 06:53:56 INFO - PID 9426 | 06:53:59 INFO - PID 9426 | Cycle 1(1): loaded http://localhost:47489/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47489/tests/kraken/driver.html?testName=imaging-darkroom) 06:54:00 INFO - PID 9426 | RSS: Main: 175980544 06:54:00 INFO - PID 9426 | 06:54:03 INFO - PID 9426 | Cycle 1(1): loaded http://localhost:47489/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47489/tests/kraken/driver.html?testName=imaging-desaturate) 06:54:04 INFO - PID 9426 | RSS: Main: 176128000 06:54:04 INFO - PID 9426 | 06:54:07 INFO - PID 9426 | Cycle 1(1): loaded http://localhost:47489/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47489/tests/kraken/driver.html?testName=json-parse-financial) 06:54:08 INFO - PID 9426 | RSS: Main: 176340992 06:54:08 INFO - PID 9426 | 06:54:09 INFO - PID 9426 | Cycle 1(1): loaded http://localhost:47489/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47489/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:54:10 INFO - PID 9426 | RSS: Main: 176480256 06:54:10 INFO - PID 9426 | 06:54:11 INFO - PID 9426 | Cycle 1(1): loaded http://localhost:47489/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47489/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:54:12 INFO - PID 9426 | RSS: Main: 176517120 06:54:12 INFO - PID 9426 | 06:54:14 INFO - PID 9426 | Cycle 1(1): loaded http://localhost:47489/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47489/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:54:15 INFO - PID 9426 | RSS: Main: 173420544 06:54:15 INFO - PID 9426 | 06:54:17 INFO - PID 9426 | Cycle 1(1): loaded http://localhost:47489/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47489/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:54:18 INFO - PID 9426 | RSS: Main: 173891584 06:54:18 INFO - PID 9426 | 06:54:21 INFO - PID 9426 | Cycle 1(1): loaded http://localhost:47489/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47489/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:54:22 INFO - PID 9426 | RSS: Main: 173875200 06:54:22 INFO - PID 9426 | 06:54:24 INFO - PID 9426 | Cycle 1(1): loaded http://localhost:47489/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47489/tests/kraken/driver.html?testName=ai-astar) 06:54:24 INFO - PID 9426 | RSS: Main: 175394816 06:54:24 INFO - PID 9426 | 06:54:24 INFO - PID 9426 | __start_tp_report 06:54:24 INFO - PID 9426 | _x_x_mozilla_page_load 06:54:24 INFO - PID 9426 | _x_x_mozilla_page_load_details 06:54:24 INFO - PID 9426 | |i|pagename|runs| 06:54:24 INFO - PID 9426 | |0;ai-astar;98;108;101;107;107;118;108;100;112;112 06:54:24 INFO - PID 9426 | |1;audio-beat-detection;149;116;145;180;117;147;147;118;176;117 06:54:24 INFO - PID 9426 | |2;audio-dft;162;161;162;162;163;162;163;170;165;162 06:54:24 INFO - PID 9426 | |3;audio-fft;76;73;73;177;76;74;73;74;156;75 06:54:24 INFO - PID 9426 | |4;audio-oscillator;80;79;134;80;87;80;136;80;80;124 06:54:24 INFO - PID 9426 | |5;imaging-gaussian-blur;113;117;117;117;116;114;117;113;114;114 06:54:24 INFO - PID 9426 | |6;imaging-darkroom;147;148;148;147;149;148;149;148;148;150 06:54:24 INFO - PID 9426 | |7;imaging-desaturate;94;95;93;99;94;94;94;93;93;93 06:54:24 INFO - PID 9426 | |8;json-parse-financial;55;55;52;52;54;52;52;52;52;53 06:54:24 INFO - PID 9426 | |9;json-stringify-tinderbox;38;37;37;37;42;37;37;38;38;38 06:54:24 INFO - PID 9426 | |10;stanford-crypto-aes;75;78;76;75;76;75;73;74;74;74 06:54:24 INFO - PID 9426 | |11;stanford-crypto-ccm;121;128;129;119;116;128;129;121;116;155 06:54:24 INFO - PID 9426 | |12;stanford-crypto-pbkdf2;170;172;175;176;175;168;173;167;177;171 06:54:24 INFO - PID 9426 | |13;stanford-crypto-sha256-iterative;79;66;73;65;67;62;65;64;70;64 06:54:24 INFO - PID 9426 | __end_tp_report 06:54:24 INFO - PID 9426 | __start_cc_report 06:54:24 INFO - PID 9426 | _x_x_mozilla_cycle_collect,906 06:54:24 INFO - PID 9426 | __end_cc_report 06:54:24 INFO - PID 9426 | __startTimestamp1504187664417__endTimestamp 06:54:24 INFO - PID 9426 | 06:54:24 INFO - PID 9426 | ------- Summary: start ------- 06:54:24 INFO - PID 9426 | Number of tests: 14 06:54:24 INFO - PID 9426 | 06:54:24 INFO - PID 9426 | [#0] ai-astar Cycles:10 Average:107.10 Median:110.00 stddev:6.14 (5.6%) stddev-sans-first:5.56 06:54:24 INFO - PID 9426 | Values: 98.0 108.0 101.0 107.0 107.0 118.0 108.0 100.0 112.0 112.0 06:54:24 INFO - PID 9426 | 06:54:24 INFO - PID 9426 | [#1] audio-beat-detection Cycles:10 Average:141.20 Median:147.00 stddev:24.05 (16.4%) stddev-sans-first:25.34 06:54:24 INFO - PID 9426 | Values: 149.0 116.0 145.0 180.0 117.0 147.0 147.0 118.0 176.0 117.0 06:54:24 INFO - PID 9426 | 06:54:24 INFO - PID 9426 | [#2] audio-dft Cycles:10 Average:163.20 Median:162.50 stddev:2.62 (1.6%) stddev-sans-first:2.74 06:54:24 INFO - PID 9426 | Values: 162.0 161.0 162.0 162.0 163.0 162.0 163.0 170.0 165.0 162.0 06:54:24 INFO - PID 9426 | 06:54:24 INFO - PID 9426 | [#3] audio-fft Cycles:10 Average:92.70 Median:74.00 stddev:39.23 (53.0%) stddev-sans-first:41.14 06:54:24 INFO - PID 9426 | Values: 76.0 73.0 73.0 177.0 76.0 74.0 73.0 74.0 156.0 75.0 06:54:24 INFO - PID 9426 | 06:54:24 INFO - PID 9426 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:80.00 stddev:24.67 (30.8%) stddev-sans-first:25.48 06:54:24 INFO - PID 9426 | Values: 80.0 79.0 134.0 80.0 87.0 80.0 136.0 80.0 80.0 124.0 06:54:24 INFO - PID 9426 | 06:54:24 INFO - PID 9426 | [#5] imaging-gaussian-blur Cycles:10 Average:115.20 Median:116.50 stddev:1.75 (1.5%) stddev-sans-first:1.67 06:54:24 INFO - PID 9426 | Values: 113.0 117.0 117.0 117.0 116.0 114.0 117.0 113.0 114.0 114.0 06:54:24 INFO - PID 9426 | 06:54:24 INFO - PID 9426 | [#6] imaging-darkroom Cycles:10 Average:148.20 Median:148.00 stddev:0.92 (0.6%) stddev-sans-first:0.87 06:54:24 INFO - PID 9426 | Values: 147.0 148.0 148.0 147.0 149.0 148.0 149.0 148.0 148.0 150.0 06:54:24 INFO - PID 9426 | 06:54:24 INFO - PID 9426 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:1.81 (1.9%) stddev-sans-first:1.92 06:54:24 INFO - PID 9426 | Values: 94.0 95.0 93.0 99.0 94.0 94.0 94.0 93.0 93.0 93.0 06:54:24 INFO - PID 9426 | 06:54:24 INFO - PID 9426 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:52.50 stddev:1.29 (2.5%) stddev-sans-first:1.12 06:54:24 INFO - PID 9426 | Values: 55.0 55.0 52.0 52.0 54.0 52.0 52.0 52.0 52.0 53.0 06:54:24 INFO - PID 9426 | 06:54:24 INFO - PID 9426 | [#9] json-stringify-tinderbox Cycles:10 Average:37.90 Median:38.00 stddev:1.52 (4.0%) stddev-sans-first:1.62 06:54:24 INFO - PID 9426 | Values: 38.0 37.0 37.0 37.0 42.0 37.0 37.0 38.0 38.0 38.0 06:54:24 INFO - PID 9426 | 06:54:24 INFO - PID 9426 | [#10] stanford-crypto-aes Cycles:10 Average:75.00 Median:75.00 stddev:1.41 (1.9%) stddev-sans-first:1.50 06:54:24 INFO - PID 9426 | Values: 75.0 78.0 76.0 75.0 76.0 75.0 73.0 74.0 74.0 74.0 06:54:24 INFO - PID 9426 | 06:54:24 INFO - PID 9426 | [#11] stanford-crypto-ccm Cycles:10 Average:126.20 Median:128.00 stddev:11.38 (8.9%) stddev-sans-first:11.91 06:54:24 INFO - PID 9426 | Values: 121.0 128.0 129.0 119.0 116.0 128.0 129.0 121.0 116.0 155.0 06:54:24 INFO - PID 9426 | 06:54:24 INFO - PID 9426 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.40 Median:174.00 stddev:3.41 (2.0%) stddev-sans-first:3.50 06:54:24 INFO - PID 9426 | Values: 170.0 172.0 175.0 176.0 175.0 168.0 173.0 167.0 177.0 171.0 06:54:24 INFO - PID 9426 | 06:54:24 INFO - PID 9426 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:66.50 stddev:5.15 (7.7%) stddev-sans-first:3.38 06:54:24 INFO - PID 9426 | Values: 79.0 66.0 73.0 65.0 67.0 62.0 65.0 64.0 70.0 64.0 06:54:24 INFO - PID 9426 | -------- Summary: end -------- 06:54:24 INFO - PID 9426 | 06:54:24 INFO - PID 9426 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 06:54:25 INFO - TEST-INFO | 9426: exit 0 06:54:25 INFO - TEST-OK | kraken | took 61681ms 06:54:25 INFO - SUITE-END | took 1173s 06:54:25 INFO - Completed test suite (00:19:33) 06:54:25 INFO - javascript benchmark 06:54:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8606.518667231878, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3289.1448223461894, 3296.9175770605734, 3306.2395649000737, 3328.251143492716, 3335.8165300908454, 6282.623042617446, 6297.748090076398, 6346.048092786084, 6370.171877655904, 6409.070684750708, 6135.0, 6215.937840621593, 6237.251529816417, 6278.058291256314, 6287.0569414587835, 5735.139729040657, 5736.45503677151, 5754.4533269339345, 5780.682062486561, 5882.029465138247, 5797.913031304533, 5821.796237131702, 5833.416658334175, 5913.704314784265, 5931.6441013539325, 5690.174924635905, 5723.198752174699, 5754.136879468071, 5803.8258852234285, 5888.440598143169, 5716.714164291769, 5814.447627475383, 5853.239078919756, 5905.763769449217, 5908.675022873783, 5762.0, 5779.422057794229, 5850.76596936122, 5870.178175055496, 5873.647581145145, 6053.485453736438, 6101.267847858286, 6124.897518446678, 6130.203073600448, 6373.649449280288, 6538.509611779135, 6549.967250163766, 6744.696488658022, 6829.0, 6831.726730930757, 6731.528792984493, 6744.494162937799, 6748.561343512656, 6751.561148525379, 6780.457563394916, 6193.318734939198, 6239.594426362317, 6248.718807653667, 6284.308726040132, 6309.842253943688, 6750.392464678104, 6867.965660171667, 6885.965570172218, 6951.200611929592, 6951.2353641099435, 6593.208814942238, 6660.666966651745, 6665.700043498055, 6671.499637527205, 6690.9294512877705, 6777.49168812341, 6813.420859226922, 6856.348646878539, 6861.416779573792, 6864.931350686529, 6713.597184168965, 6723.63020033893, 6793.864122717617, 6804.115464989619, 6808.93191068083, 6519.967400162969, 6616.4376028037195, 6735.6295403753265, 6741.629210393476, 6816.931830681629, 5260.0, 5350.304460420199, 5357.732113394315, 5361.651492652965, 5362.195670649356, 4670.299455081697, 4670.719756814534, 4714.434267887876, 4734.73958932262, 4779.0, 5842.38654941225, 5898.14476900847, 5935.910961335583, 5938.28740551128, 5973.313068997034, 6631.535792494576, 6632.303608121078, 6692.698828552727, 6705.027770973182, 6823.658817059128, 5765.106408506688, 5832.766689332379, 5916.763329466773, 5953.196318497034, 5978.671173085435, 5812.564057695689, 5856.267966504187, 5885.323187833368, 5892.882142357129, 5896.380880007538, 6502.60984340941, 6551.868962620816, 6660.333966603301, 6682.565633233921, 6698.933010669928, 6581.638009909501, 6703.865922681616, 6762.661866906733, 6789.422899053636, 6863.210730766026, 6466.612003279818, 6478.028295755675, 6496.415322620986, 6504.284528701744, 6539.803805885831, 6523.869522609426, 6544.3782840630065, 6548.738050478118, 6622.470202383702, 6654.5341826073645, 6670.299618540177, 6674.43267322283, 6775.322467753185, 6777.728890844311, 6778.8983165251575, 6573.638449885302, 6596.80209593713, 6607.141071660845, 6609.0, 6611.140551728245, 6348.0, 6570.5400621956005, 6643.2360278568585, 6659.2008958925235, 6660.700268488026, 6290.937090629218, 6415.903761443676, 6418.871622567616, 6419.518536109717, 6433.035044743326, 6396.0, 6522.21733391996, 6673.0, 6678.966605166943, 6763.6618169092335, 6208.317085120723, 6264.812055638338, 6287.056941458635, 6440.29156792743, 6502.154719886385], "value": 6154.534337230426, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [797.4617133434933, 851.4508142248249, 877.6971944679087, 1142.337444282317, 1160.7678464307141, 29737.0, 39500.60499395009, 39913.20173596526, 39976.600233997655, 40001.1999760005, 40467.59532404675, 40861.38707919376, 40888.38667419991, 40938.18123637533, 40971.385429218586, 7597.962010189941, 7755.806104847367, 7756.224377562226, 7762.30139287464, 7768.262015108556, 7533.434992375566, 7593.316601505863, 7693.192214817447, 7694.846103077935, 7695.692172313101, 7697.499662521918, 7726.459147859624, 7744.109427415835, 7748.070231572219, 7750.031246094239, 7596.696132154735, 7662.195469475736, 7691.153973062987, 7694.0, 7696.037995250594, 7598.278163574451, 7649.196834332371, 7655.885161722578, 7659.885101723423, 7662.8084297893, 38134.0, 38188.23623527541, 38201.23597528034, 38204.80897595494, 38299.808500957595, 3556.875509357186, 3580.5882323532865, 3586.444101164324, 3589.8025608591515, 3591.2099338145567, 30447.23881902948, 30482.390352192833, 30540.389192216033, 30541.23646908845, 30669.386612267852, 4951.480094590088, 5129.204973229155, 5203.713795741232, 5229.2156176573435, 5231.058409486291, 194363.0, 196540.0, 196687.99999999857, 197401.0, 197808.0, 47701.0, 48625.0, 49467.0, 49558.0, 49577.25634115491, 50218.0, 50374.49625503772, 50388.748056260214, 50472.747636261585, 50477.24284135741, 12908.870911290955, 12980.221186728826, 12988.155769874928, 13010.349482525837, 14507.129572225489, 11664.591739289084, 11711.1802173847, 11718.0, 11764.058875289957, 11766.941165294118, 12389.814152787716, 12703.809442858364, 14494.202818844853, 14776.926115369355, 14797.926010370094, 5873.236479257755, 5955.0, 5958.165856780054, 5990.341062483123, 5999.610025348338, 1013.0983424751977, 1018.709667744704, 1025.4513835098187, 1031.1802117316602, 1051.553089936789, 22541.05531532008, 22629.660555091687, 22654.0, 22986.310410687707, 23033.424164396023, 1102.1403305421784, 1114.9832752508719, 1132.7224829916722, 1133.5692436873935, 1136.9260998035102, 12341.321227332402, 12389.946854517286, 12394.070444716683, 12426.440810163564, 12453.718950530438, 240119.0, 242689.0, 242835.0, 242884.0, 244097.0, 50436.74781626142, 50764.0, 50935.0, 50958.235626465634, 51347.48652513427, 1396.1762560089596, 1442.9899070650376, 1447.2488289170012, 1450.840407555168, 1467.288365142905, 22379.328620141423, 22481.325560233217, 22585.322440326818, 22639.320820375415, 22666.546669066855, 36938.076548086516, 36940.076498087765, 37034.444483332234, 37035.0, 37072.258554828215, 197208.0, 197558.0, 198157.0, 198162.0, 198592.0, 34620.13449663779, 34633.134171645914, 34894.0, 34898.47652285269, 34913.0, 40844.1831163381, 40871.18257634771, 42381.36427953521, 45014.549854501034, 46922.765386172854, 33344.333113338085, 33475.163120922174, 33589.3282134351, 33979.830100850326, 34303.31393372069, 34007.0, 34415.82792086023, 34549.13627159341, 34593.0, 34692.132696682784, 38468.6153138465, 38893.2221355577, 39075.80462097671, 39259.82220533388, 39277.0, 33491.0, 33697.663023370435, 33776.49335259924, 33900.49149262714, 34152.0, 24383.39041523905, 24428.877855610608, 24527.264182074567, 24857.1300004499, 25276.62085068762], "value": 17185.706289790287, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.1974675580806, 579.2787978966188, 579.5566391710342, 590.4538302070584, 592.2922108080844, 21540.676889846643, 22258.220962266325, 22311.776882231174, 22342.329730108082, 22365.664515032287, 24972.25083247501, 25287.0, 25365.239042828744, 25388.0, 25502.74497255027, 6461.450776683988, 6628.701708423108, 6653.933460665404, 6654.733810647568, 6655.201375834882, 6492.156019717454, 6538.738450461976, 6540.313267106957, 6544.083828264047, 6680.131582894242, 6968.930310696905, 6974.128233970754, 6974.581525108484, 6977.79066628002, 6983.82540436488, 6962.373386395224, 6966.268541803089, 6969.512134150612, 6969.825754356157, 6970.965145174242, 6814.62519561424, 6815.659217039129, 6822.147231596051, 6822.624755638438, 6848.246692863781, 30316.24209394783, 30484.0, 31014.844925775225, 31137.377252454826, 31153.376932461222, 2059.227789578908, 2094.98952505238, 2095.0886364431367, 2104.5790841831695, 2107.947301317464, 20135.39593812188, 20756.065977030918, 20777.480562985897, 20845.58308833815, 20852.47868803277, 2358.7995020423114, 2360.409897525619, 2362.6503867606457, 2387.4866903615807, 2446.2661201639617, 138190.30904845512, 139783.30108349494, 139875.30062349726, 140505.0, 140611.0, 19723.56219850522, 21190.78809211919, 21215.8939205303, 21440.78559214388, 21978.230761923576, 42814.357784633256, 42921.785391072845, 43042.569574304485, 43071.0, 43144.78427607842, 10940.56237750497, 11019.0, 11029.0, 11131.888681113247, 11180.21738478315, 9073.364864459556, 9081.954590227007, 9162.267018638493, 9176.17414432691, 9219.078092190728, 8458.915410845935, 9180.21968132703, 9275.76810579741, 9303.302252331103, 10990.560377584807, 2562.2981828344823, 2648.3379155211196, 2655.7344265573674, 2687.9328016799345, 2694.2321438389963, 963.556763888605, 980.3872579637726, 994.3089552760762, 999.3853779925294, 1006.833872411044, 19426.514337141685, 19685.212591496467, 19710.310139145062, 20243.898780506002, 20430.08064637095, 1025.620520407439, 1046.931949423285, 1055.472263868066, 1058.1270451877233, 1060.1201003167353, 10840.945795270973, 13015.544455943997, 13248.40382182804, 14218.573442796733, 14554.781678274834, 463881.0, 476027.0, 487064.0, 488181.0, 502147.0, 117546.0, 117565.0, 118982.40508797401, 119109.0, 122066.38966805287, 1213.8546862464761, 1216.397883047897, 1234.6481252842896, 1239.9442025108892, 1248.0514808745238, 20815.479613009797, 20921.0, 21213.681794773398, 21246.0, 21556.814375209513, 55282.447175527726, 58077.709611451675, 58596.70701646464, 58838.41161588329, 60560.697196515495, 74004.62997684977, 75020.62489687734, 75075.62462187654, 75305.0, 75544.24455755373, 14037.859621403655, 14124.717505650035, 14198.148111113367, 14282.714345713235, 14310.856891430953, 18015.459536213933, 18021.2791488339, 18058.36795712196, 18072.457826265232, 22528.32415027552, 17097.82902171012, 17201.225944832262, 17214.569635759206, 17232.827671723124, 17459.912700436926, 15743.763843542585, 16856.66286674284, 17693.292268309637, 17845.910770446066, 18104.637907242046, 17997.640047199246, 19493.8050619492, 19496.805031949498, 19658.901705491382, 19907.800921991173, 14550.12699238049, 14696.265186740406, 14813.0, 14820.110793352433, 14843.703125937636, 18341.54146146357, 18660.160292787128, 18665.533361665523, 18683.252669893594, 19301.4209573713], "value": 12559.482150337963, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1310.4561606933123, 1367.8905687544998, 1371.3417559571403, 1375.669839238583, 1377.8208832851728, 12597.496100155982, 14819.18494482803, 15514.767278490832, 15564.299606517692, 15615.063096214235, 12914.418851151697, 15077.396904123849, 15517.767233491506, 15519.767203491956, 15587.0, 2709.1872438268488, 2769.9584506232422, 2770.8476033818133, 2772.099067802972, 2773.251222170013, 2621.934451638705, 2700.1224602004327, 2703.3106557827728, 2795.9860200698968, 2812.9718702812916, 2773.320536468568, 2801.831890086591, 2801.957970630442, 2802.1733588591314, 2802.383475635357, 2674.7993900457445, 2690.8250963687296, 2694.1648089092446, 2699.095802906019, 2700.7299270072936, 2673.4920365130565, 2708.295843080813, 2709.498742732601, 2722.6250743374667, 2724.8910043598235, 14925.0, 15278.541643750705, 15280.465183718516, 15287.770683439758, 15290.311935962909, 8010.198980102002, 8370.204830541088, 8372.16278372164, 8373.911391519125, 8381.664733410656, 5208.5572726318305, 5390.434004429557, 5477.698726570037, 5508.063629183049, 5556.777728890839, 1781.4655603318952, 1833.2758560368713, 1851.527860395604, 1879.2201236486978, 1894.5358387195226, 25355.366115847068, 25501.0, 25572.744272557407, 25574.23277301684, 25593.36016599581, 6564.901526477011, 6729.25978142404, 6950.617716025566, 6951.443884489228, 7036.683349249296, 16723.581910452336, 17242.827571724374, 17315.56711082233, 17338.91330543339, 17565.560860978323, 7897.1707970662255, 8154.8776768348525, 8191.549464779375, 8192.0, 8195.385346098947, 7442.06974128234, 7496.100467943882, 7526.0, 7529.924700752922, 7548.433867459962, 5228.843134705965, 5262.236975638511, 5263.526282634582, 5294.047071527162, 5406.513413792777, 1483.050847457628, 1489.3744627256578, 1490.8509149085005, 1493.9103653780592, 1504.7441934870878, 855.619249433996, 879.520661239626, 887.0154128916897, 887.7159309021051, 898.766320756595, 7691.961540192375, 7713.65288562016, 7730.497517661334, 7762.6894924203525, 7852.057753069668, 707.8124297061283, 717.5658726470515, 737.4653376302246, 737.5021860244314, 746.0227102495747, 5155.819546315936, 5182.585393168538, 5409.080456322435, 5631.8592035199445, 5660.462256085665, 19985.30051448192, 21144.577108457746, 21150.048247828883, 22024.44938876509, 22082.0, 21279.361619151452, 21371.67942480864, 21376.0, 21378.572428551342, 21536.246231381825, 10592.364458132537, 11297.300530324137, 11603.07175425964, 11603.941980289876, 11610.187286890006, 8393.958030210055, 8544.786380340542, 8554.486730795923, 8677.479351238946, 8750.212480876751, 18407.263709452007, 18566.443006709822, 18662.72005919885, 18718.438446846616, 18864.33974810875, 18731.344402945833, 18967.241310347432, 19130.80869191346, 19284.903575482593, 19291.51771205731, 11623.88376116228, 11941.104417168573, 11946.76106477883, 11951.22317049389, 11951.402429878644, 13747.587572372844, 14058.859411405754, 14077.788833167715, 14106.929465352607, 14108.153510789385, 10628.89371106279, 10690.347690452118, 10711.25021248505, 10882.727931801768, 11040.0, 10331.276810623183, 10602.840957385799, 10679.626213082505, 10746.892531074589, 10824.296420732628, 8810.603522841615, 8855.247303978977, 8888.6000129993, 9004.234640055669, 9016.0, 6674.466042716668, 7321.853562928605, 7327.303906128909, 7338.229485903903, 7349.6692648832, 7329.963350183215, 7355.301246381585, 7374.631268436449, 7454.813629659302, 7459.291367320096], "value": 6605.384180279762, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.81893354441405, 500.95741861941735, 527.2986927386576, 528.9233061206123, 537.7768226186133, 6191.876162476748, 6228.968855155724, 6238.656873871935, 6250.968745156273, 6268.905966410502, 5904.498117659993, 6132.417420345071, 6140.140380346755, 6150.415710507495, 6169.845753856144, 5111.361079865017, 5171.19846423803, 5190.896182076356, 5222.477752224766, 5235.816746413876, 5045.520675535819, 5127.154019586764, 5134.460881607433, 5152.201408781645, 5203.543007957759, 5102.438731739506, 5162.199859021857, 5195.558377537895, 5197.194434862602, 5239.187925871496, 5133.178691409394, 5144.4598317176715, 5150.768215430277, 5161.948380516222, 5178.922316165279, 4830.372051633265, 4842.685225460334, 4847.854564363075, 4859.173940430135, 4876.390451193601, 6069.119977603223, 6150.200473938404, 6171.722272497748, 6175.197224360849, 6250.749970001195, 5966.522678185735, 5988.0, 5992.543057346467, 6029.60807547508, 6054.334023257438, 6443.967780161116, 6465.935340646627, 6497.967510162466, 6572.342765723437, 6588.242352129519, 5146.176611742103, 5180.870478238036, 5242.501962313574, 5288.418273989858, 5298.6555873868465, 6833.145856767904, 6886.24251332353, 6942.479314051416, 6986.61573613451, 7005.54463959841, 5266.368035835724, 5272.710000949908, 5312.229726689686, 5325.680459172462, 5355.4376790436445, 5397.271368365299, 5414.539764120015, 5435.782568697208, 5437.45625437461, 5450.672959622435, 5920.5559583031445, 5923.733431995571, 5937.614055086406, 5941.1385349125, 5997.55018373615, 6401.487880969511, 6445.226572811199, 6566.409023187936, 6617.53677242598, 6635.279535390467, 1744.6597913406763, 1786.240847639748, 1824.6806808808406, 1875.8499320054636, 1899.990500047491, 1360.7482615716124, 1371.3143428285857, 1383.2876068824564, 1385.5773988933472, 1403.0669604712753, 5596.916046259309, 5681.34664513578, 5705.177531054203, 5711.286089238845, 5776.480116789425, 6372.4583410409705, 6420.454261387741, 6554.80335589933, 6556.377144171296, 6657.800265992028, 5728.283964504437, 5735.111057785966, 5785.82642520725, 5797.217375654317, 5845.064789633639, 3533.5759708835103, 3564.2891412122326, 3565.484669015658, 3592.1558433768046, 3594.51474051005, 6568.211814582281, 6586.4401525870835, 6588.53880228389, 6589.209294884548, 6624.536282460278, 6916.273791251947, 6921.082474484244, 6938.548994315353, 6941.965290173618, 6945.40964018054, 6536.640484773193, 6539.738410463623, 6564.67176641166, 6574.375434333826, 6579.0, 6626.6686665667485, 6683.429394092287, 6690.531662783558, 6708.731650733916, 6748.6963086660235, 6589.604623722592, 6622.90065649006, 6661.400473957173, 6673.399394054559, 6677.933220667731, 6524.575902566318, 6525.314841941527, 6538.215414150332, 6565.507586930904, 6572.572782769104, 4938.629602779734, 4940.888300132441, 4941.925871112008, 4955.2567114933045, 4970.676906001243, 6597.0, 6617.536772425884, 6649.240565165403, 6693.531452798257, 6709.85284708908, 6139.72371243304, 6158.168647232566, 6317.6209427434505, 6339.714712837841, 6422.486201103994, 6375.776847810304, 6471.546991710535, 6478.384553467413, 6482.0, 6492.0, 6359.395857393725, 6362.300146983735, 6376.266729326187, 6385.68071596409, 6410.775622853178, 6350.872982540231, 6371.776987805404, 6456.192975878015, 6517.8696426072165, 6563.619735835953, 6275.780347687809, 6294.527910406646, 6296.937030629819, 6332.556721029484, 6366.3633636637815], "value": 5138.515732691629, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [902.6344330546127, 915.6154415145638, 920.5535315372039, 925.3846192282133, 937.756183392318, 17849.910750446244, 18771.343002994898, 18796.71804922924, 18835.99999999998, 21144.4713882153, 20461.693074603892, 21317.893410532964, 21401.143954241852, 21597.244096456627, 21626.783732162672, 946.2713710442972, 955.6416343871049, 959.491469521155, 965.3339195954804, 966.6809952715587, 941.7975135345899, 956.689076050283, 957.760559860035, 967.3808762392075, 976.414151509096, 1054.978900421991, 1055.8996895294936, 1056.7410984308856, 1059.6926891201542, 1060.3107979813105, 1039.7244730146517, 1041.6406339812752, 1050.4905121016302, 1051.55834549489, 1052.5947510208587, 763.065245074779, 765.1736124985002, 765.3800421658475, 766.8427972265672, 772.2200577416793, 18318.0, 18552.814471855378, 18601.81398186028, 18629.0, 18849.340273090376, 20412.387628371172, 20513.692294615594, 20890.895545522326, 20943.581128377347, 21519.246826361003, 16806.663866722596, 16929.492115236564, 17267.3956411525, 17418.912905435518, 17432.21555030027, 3745.045013521562, 3755.9812200939095, 3764.8117594120185, 3776.8300426480873, 3779.79211143387, 112716.4364178182, 114271.42864285625, 114410.0, 117420.41289793582, 119147.40426297899, 15883.205839707969, 16392.918035409748, 16456.753148702777, 16465.25906334218, 16476.917615411847, 29133.56299655507, 30672.386552269276, 30896.53655195174, 30907.536386954213, 30927.381452370828, 8961.910380896108, 8973.237274831712, 9221.631134754669, 9249.95375023134, 9253.537323133816, 4264.893377665521, 4276.657867370603, 4378.890527736832, 4383.956160438354, 4519.0, 5242.030224408497, 5363.5440987516495, 5371.274877891435, 5371.677699338053, 5372.059889519319, 1066.7013236293851, 1080.0657431322006, 1102.3551222534907, 1104.9723756906142, 1105.093823065095, 668.9498287628447, 673.1383828699272, 683.9829004274833, 688.7795905310353, 696.6864910790165, 11325.603603873826, 11736.295822250693, 11858.110641701907, 12223.93888030554, 12477.313747743961, 681.7273090763734, 693.9722411103601, 702.3889216381781, 709.670003448399, 711.2212127720138, 5351.143816989341, 5422.945770542323, 5436.10304299794, 5969.3732158122775, 6272.529560282997, 107644.4085200657, 108375.0, 108820.4558977216, 111402.0, 112215.4389228065, 92660.53669731609, 93630.0, 95071.0, 97337.0, 98858.50570747245, 84170.0, 85812.0, 86044.56977715071, 89572.0, 90134.54932725294, 12681.30252836103, 12798.0, 12864.742705145658, 12870.742585148057, 12876.484940602271, 39602.405963911144, 39738.0, 39832.0, 40039.0, 41813.0, 51405.742971284904, 52983.73508132435, 54009.18986215288, 54157.72921135527, 54986.0, 16158.0, 16591.585210369838, 17076.573085672957, 17107.828921710625, 17166.570835729206, 18562.628747424707, 19930.60138797187, 20023.0, 20031.599368012266, 20316.288929887975, 16956.152192390084, 17627.206775694867, 17649.205785739417, 18222.0, 18646.81353186505, 17288.827111728722, 17672.0, 17733.46799596014, 17999.820001800337, 18066.638667226845, 16818.327266909677, 17475.56311092233, 17545.0, 17661.64676706433, 17724.291028358723, 11703.356315402734, 11724.531018759155, 11958.162928594915, 12295.754084918073, 12616.621501354974, 13070.281134537692, 13182.868171318163, 13244.072914896251, 13471.865281347453, 13515.932420337835], "value": 9012.990372560225, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1489.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 108.0, 101.0, 107.0, 107.0, 118.0, 108.0, 100.0, 112.0, 112.0], "value": 107.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 116.0, 145.0, 180.0, 117.0, 147.0, 147.0, 118.0, 176.0, 117.0], "value": 141.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 162.0, 163.0, 162.0, 163.0, 170.0, 165.0, 162.0], "value": 163.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 73.0, 177.0, 76.0, 74.0, 73.0, 74.0, 156.0, 75.0], "value": 92.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 134.0, 80.0, 87.0, 80.0, 136.0, 80.0, 80.0, 124.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 117.0, 117.0, 117.0, 116.0, 114.0, 117.0, 113.0, 114.0, 114.0], "value": 115.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 148.0, 147.0, 149.0, 148.0, 149.0, 148.0, 148.0, 150.0], "value": 148.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 93.0, 99.0, 94.0, 94.0, 94.0, 93.0, 93.0, 93.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 52.0, 52.0, 54.0, 52.0, 52.0, 52.0, 52.0, 53.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 37.0, 37.0, 37.0, 42.0, 37.0, 37.0, 38.0, 38.0, 38.0], "value": 37.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 76.0, 75.0, 76.0, 75.0, 73.0, 74.0, 74.0, 74.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 128.0, 129.0, 119.0, 116.0, 128.0, 129.0, 121.0, 116.0, 155.0], "value": 126.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 172.0, 175.0, 176.0, 175.0, 168.0, 173.0, 167.0, 177.0, 171.0], "value": 172.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 66.0, 73.0, 65.0, 67.0, 62.0, 65.0, 64.0, 70.0, 64.0], "value": 67.5, "unit": "score"}]}]} 06:54:25 INFO - Return code: 0 06:54:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:54:25 INFO - # TBPL SUCCESS # 06:54:25 INFO - Running post-action listener: _package_coverage_data 06:54:25 INFO - Running post-action listener: _resource_record_post_action 06:54:25 INFO - [mozharness: 2017-08-31 13:54:25.731778Z] Finished run-tests step (success) 06:54:25 INFO - Running post-run listener: _resource_record_post_run 06:54:25 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 0; Write bytes: 391696384; Read time: 0; Write time: 356992 06:54:25 INFO - TinderboxPrint: CPU usage
13.1% 06:54:25 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 06:54:25 INFO - TinderboxPrint: I/O write bytes / time
391,696,384 / 356,992 06:54:25 INFO - TinderboxPrint: CPU idle
8,251.3 (86.9%) 06:54:25 INFO - TinderboxPrint: CPU user
1,193.9 (12.6%) 06:54:25 INFO - TinderboxPrint: Swap in / out
0 / 0 06:54:25 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 106672128; Read time: 0; Write time: 134272 06:54:25 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 06:54:25 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 285024256; Read time: 0; Write time: 222720 06:54:26 INFO - Running post-run listener: _upload_blobber_files 06:54:26 INFO - Blob upload gear active. 06:54:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:54:26 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 06:54:26 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'] 06:54:26 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 06:54:26 INFO - (blobuploader) - INFO - Open directory for files ... 06:54:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:54:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:54:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:54:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:54:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:54:28 INFO - (blobuploader) - INFO - Done attempting. 06:54:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:54:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:54:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:54:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:54:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:54:31 INFO - (blobuploader) - INFO - Done attempting. 06:54:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:54:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:54:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:54:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:54:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:54:32 INFO - (blobuploader) - INFO - Done attempting. 06:54:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:54:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:54:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:54:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:54:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:54:33 INFO - (blobuploader) - INFO - Done attempting. 06:54:33 INFO - (blobuploader) - INFO - Iteration through files over. 06:54:33 INFO - Return code: 0 06:54:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:54:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:54:33 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99f7192fb2dff620fbc734482d895324b4682c545bac89e7219877ff4d71c773760fce9c1faa6def72ed7e60b252b6dfcaaee56800d499fedf020459ae32c2a0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60fbd462539746c5dab2a51e6a4404388f0d10a748d611bc98a8b08e2a93084d8286d81042df154a8e24003e4355702e11cd203d1b865d94b038c2ab345626cb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4fa2fe18db0e71cddf34a95e43179db3308bf8611bbc96ba9360dd0055a0c31915494ae5924753f14b70d03fd841f7e380df985d29a7ff6d6669209818fe51a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46e064e9969931d3f41da276deb4af11f0a18f9d07734156cd8a1b646276085f2c2788115ecdb534825668f36c234c7408227e09724a445226c53010fcf8ff31"} 06:54:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:54:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:54:33 INFO - Contents: 06:54:33 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99f7192fb2dff620fbc734482d895324b4682c545bac89e7219877ff4d71c773760fce9c1faa6def72ed7e60b252b6dfcaaee56800d499fedf020459ae32c2a0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60fbd462539746c5dab2a51e6a4404388f0d10a748d611bc98a8b08e2a93084d8286d81042df154a8e24003e4355702e11cd203d1b865d94b038c2ab345626cb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4fa2fe18db0e71cddf34a95e43179db3308bf8611bbc96ba9360dd0055a0c31915494ae5924753f14b70d03fd841f7e380df985d29a7ff6d6669209818fe51a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46e064e9969931d3f41da276deb4af11f0a18f9d07734156cd8a1b646276085f2c2788115ecdb534825668f36c234c7408227e09724a445226c53010fcf8ff31"} 06:54:33 INFO - Running post-run listener: copy_logs_to_upload_dir 06:54:33 INFO - Copying logs to upload dir... 06:54:33 INFO - mkdir: /builds/slave/test/build/upload/logs 06:54:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.098766 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-08-31 06:54:33.692398) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-31 06:54:33.696933) ========= 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=930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99f7192fb2dff620fbc734482d895324b4682c545bac89e7219877ff4d71c773760fce9c1faa6def72ed7e60b252b6dfcaaee56800d499fedf020459ae32c2a0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60fbd462539746c5dab2a51e6a4404388f0d10a748d611bc98a8b08e2a93084d8286d81042df154a8e24003e4355702e11cd203d1b865d94b038c2ab345626cb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4fa2fe18db0e71cddf34a95e43179db3308bf8611bbc96ba9360dd0055a0c31915494ae5924753f14b70d03fd841f7e380df985d29a7ff6d6669209818fe51a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46e064e9969931d3f41da276deb4af11f0a18f9d07734156cd8a1b646276085f2c2788115ecdb534825668f36c234c7408227e09724a445226c53010fcf8ff31"} build_url:https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011380 build_url: 'https://queue.taskcluster.net/v1/task/RI4nAJyITZ2MQzniRVqbBQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/99f7192fb2dff620fbc734482d895324b4682c545bac89e7219877ff4d71c773760fce9c1faa6def72ed7e60b252b6dfcaaee56800d499fedf020459ae32c2a0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60fbd462539746c5dab2a51e6a4404388f0d10a748d611bc98a8b08e2a93084d8286d81042df154a8e24003e4355702e11cd203d1b865d94b038c2ab345626cb", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4fa2fe18db0e71cddf34a95e43179db3308bf8611bbc96ba9360dd0055a0c31915494ae5924753f14b70d03fd841f7e380df985d29a7ff6d6669209818fe51a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46e064e9969931d3f41da276deb4af11f0a18f9d07734156cd8a1b646276085f2c2788115ecdb534825668f36c234c7408227e09724a445226c53010fcf8ff31"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-31 06:54:33.727018) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 06:54:33.727475) ========= 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=930c87414c89fc706ee2024c000001c6-1504186420.953886-1155454862 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004499 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-31 06:54:33.807370) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-31 06:54:33.810819) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-31 06:54:33.811191) ========= ========= Total master_lag: 0.18 =========