builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-037 starttime: 1505860705.19 results: success (0) revision: a0eb21bf55e1c1ae0ba311e6f2273da05c712799 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-19 15:38:25.185257) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-19 15:38:25.185644) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-19 15:38:25.185907) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-19 15:38:25.328466) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-19 15:38:25.328713) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-19 15:38:25-- 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% 41.9M=0s 2017-09-19 15:38:25 (41.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.188504 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-19 15:38:25.561883) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-19 15:38:25.562157) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014501 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-19 15:38:25.613864) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-19 15:38:25.614158) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a0eb21bf55e1c1ae0ba311e6f2273da05c712799 --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 a0eb21bf55e1c1ae0ba311e6f2273da05c712799 --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=56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-19 15:38:25,852 truncating revision to first 12 chars 2017-09-19 15:38:25,852 Setting DEBUG logging. 2017-09-19 15:38:25,852 attempt 1/10 2017-09-19 15:38:25,852 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a0eb21bf55e1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-19 15:38:26,355 attempt 1/10 2017-09-19 15:38:27,165 current task status: no status available at this point. state: STARTED 2017-09-19 15:38:27,166 sleeping for 9.00s (attempt 1/10) 2017-09-19 15:38:36,175 attempt 2/10 2017-09-19 15:38:36,505 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-19 15:38:36,714 unpacking tar archive at: mozilla-central-a0eb21bf55e1/testing/mozharness/ program finished with exit code 0 elapsedTime=11.175845 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-19 15:38:36.810206) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-19 15:38:36.813512) ========= script_repo_revision: a0eb21bf55e1c1ae0ba311e6f2273da05c712799 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-19 15:38:36.813867) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-19 15:38:36.814127) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-19 15:38:36.827299) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-09-19 15:38:36.827570) ========= /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=56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:38:37 INFO - MultiFileLogger online at 20170919 15:38:37 in /builds/slave/test 15:38:37 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 15:38:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:38:37 INFO - {'append_to_log': False, 15:38:37 INFO - 'base_work_dir': '/builds/slave/test', 15:38:37 INFO - 'blob_upload_branch': 'Firefox', 15:38:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:38:37 INFO - 'branch': 'Firefox', 15:38:37 INFO - 'buildbot_json_path': 'buildprops.json', 15:38:37 INFO - 'code_coverage': False, 15:38:37 INFO - 'config_files': ('talos/linux_config.py',), 15:38:37 INFO - 'default_actions': ('clobber', 15:38:37 INFO - 'read-buildbot-config', 15:38:37 INFO - 'download-and-extract', 15:38:37 INFO - 'populate-webroot', 15:38:37 INFO - 'create-virtualenv', 15:38:37 INFO - 'install', 15:38:37 INFO - 'setup-mitmproxy', 15:38:37 INFO - 'run-tests'), 15:38:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:38:37 INFO - 'disable_ccov_upload': False, 15:38:37 INFO - 'disable_stylo': False, 15:38:37 INFO - 'download_minidump_stackwalk': True, 15:38:37 INFO - 'download_symbols': 'ondemand', 15:38:37 INFO - 'enable_stylo': False, 15:38:37 INFO - 'enable_webrender': False, 15:38:37 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:38:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:38:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:38:37 INFO - 'gecko_profile': False, 15:38:37 INFO - 'gecko_profile_interval': 0, 15:38:37 INFO - 'installer_path': 'installer.exe', 15:38:37 INFO - 'log_level': 'info', 15:38:37 INFO - 'log_name': 'talos', 15:38:37 INFO - 'log_to_console': True, 15:38:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:38:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:38:37 INFO - 'opt_config_files': (), 15:38:37 INFO - 'pip_index': False, 15:38:37 INFO - 'suite': 'dromaeojs-e10s', 15:38:37 INFO - 'system_bits': '32', 15:38:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:38:37 INFO - 'title': 'talos-linux64-ix-037', 15:38:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:38:37 INFO - 'use_talos_json': True, 15:38:37 INFO - 'verify': 'False', 15:38:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:38:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:38:37 INFO - 'work_dir': 'build'} 15:38:37 INFO - [mozharness: 2017-09-19 22:38:37.280048Z] Running clobber step. 15:38:37 INFO - Running pre-action listener: _resource_record_pre_action 15:38:37 INFO - Running main action method: clobber 15:38:37 INFO - rmtree: /builds/slave/test/build 15:38:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:38:38 INFO - Running post-action listener: _resource_record_post_action 15:38:38 INFO - [mozharness: 2017-09-19 22:38:38.235135Z] Finished clobber step (success) 15:38:38 INFO - [mozharness: 2017-09-19 22:38:38.235232Z] Running read-buildbot-config step. 15:38:38 INFO - Running pre-action listener: _resource_record_pre_action 15:38:38 INFO - Running main action method: read_buildbot_config 15:38:38 INFO - Using buildbot properties: 15:38:38 INFO - { 15:38:38 INFO - "project": "", 15:38:38 INFO - "product": "firefox", 15:38:38 INFO - "who": "kwierso@gmail.com", 15:38:38 INFO - "installer_path": "public/build/target.tar.bz2", 15:38:38 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 15:38:38 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:38:38 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 15:38:38 INFO - "stage_platform": "linux64-qr", 15:38:38 INFO - "basedir": "/builds/slave/test", 15:38:38 INFO - "buildnumber": 78, 15:38:38 INFO - "platform": "ubuntu64_hw_qr", 15:38:38 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:38:38 INFO - "slavebuilddir": "test", 15:38:38 INFO - "taskId": "ZE_ZD0vqRaqYqCX8BZltTg", 15:38:38 INFO - "branch": "mozilla-central", 15:38:38 INFO - "script_repo_revision": "production", 15:38:38 INFO - "revision": "a0eb21bf55e1c1ae0ba311e6f2273da05c712799", 15:38:38 INFO - "slavename": "talos-linux64-ix-037", 15:38:38 INFO - "repo_path": "mozilla-central" 15:38:38 INFO - } 15:38:38 INFO - Finding installer, test and symbols from parent task. 15:38:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZE_ZD0vqRaqYqCX8BZltTg'}, attempt #1 15:38:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZE_ZD0vqRaqYqCX8BZltTg'}, attempt #1 15:38:39 INFO - Task dependencies: fNRiXVaMT5ClVdEE5NyfTw 15:38:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw'}, attempt #1 15:38:39 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.tar.bz2 15:38:39 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.test_packages.json 15:38:39 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.crashreporter-symbols.zip 15:38:39 INFO - Running post-action listener: _resource_record_post_action 15:38:39 INFO - [mozharness: 2017-09-19 22:38:39.601457Z] Finished read-buildbot-config step (success) 15:38:39 INFO - [mozharness: 2017-09-19 22:38:39.601675Z] Running download-and-extract step. 15:38:39 INFO - Running pre-action listener: _resource_record_pre_action 15:38:39 INFO - Running main action method: download_and_extract 15:38:39 INFO - mkdir: /builds/slave/test/build/tests 15:38:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:39 INFO - trying https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.test_packages.json 15:38:39 INFO - Downloading https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:38:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:38:40 INFO - Downloaded 989 bytes. 15:38:40 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:38:40 INFO - Using the following test package requirements: 15:38:40 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:38:40 INFO - u'common': [u'target.common.tests.zip'], 15:38:40 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:38:40 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:38:40 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:38:40 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:38:40 INFO - u'mozbase': [u'target.common.tests.zip'], 15:38:40 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:38:40 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:38:40 INFO - u'web-platform': [u'target.common.tests.zip', 15:38:40 INFO - u'target.web-platform.tests.tar.gz'], 15:38:40 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:38:40 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 15:38:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.common.tests.zip 15:38:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:38:40 INFO - Fetch https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.common.tests.zip into memory 15:38:41 INFO - Content-Length response header: 54731781 15:38:41 INFO - Bytes received: 54731781 15:38:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.talos.tests.zip 15:38:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:38:52 INFO - Fetch https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.talos.tests.zip into memory 15:38:52 INFO - Content-Length response header: 14508904 15:38:52 INFO - Bytes received: 14508904 15:38:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:38:52 INFO - trying https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.tar.bz2 15:38:52 INFO - Downloading https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:38:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:38:54 INFO - Downloaded 61572844 bytes. 15:38:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.tar.bz2 15:38:54 INFO - mkdir: /builds/slave/test/properties 15:38:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:38:54 INFO - Writing to file /builds/slave/test/properties/build_url 15:38:54 INFO - Contents: 15:38:54 INFO - build_url:https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.tar.bz2 15:38:54 INFO - Running post-action listener: _resource_record_post_action 15:38:54 INFO - Running post-action listener: find_tests_for_verification 15:38:54 INFO - Running post-action listener: set_extra_try_arguments 15:38:54 INFO - [mozharness: 2017-09-19 22:38:54.080180Z] Finished download-and-extract step (success) 15:38:54 INFO - [mozharness: 2017-09-19 22:38:54.080278Z] Running populate-webroot step. 15:38:54 INFO - Running pre-action listener: _resource_record_pre_action 15:38:54 INFO - Running main action method: populate_webroot 15:38:54 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:38:54 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:38:54 INFO - 'tests': ['tresize', 15:38:54 INFO - 'tcanvasmark']}, 15:38:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:38:54 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:38:54 INFO - 'tests': ['dromaeo_css', 15:38:54 INFO - 'kraken']}, 15:38:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:38:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:38:54 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:38:54 INFO - 'talos_options': ['--disable-stylo'], 15:38:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:38:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:38:54 INFO - 'tests': ['damp', 'tps']}, 15:38:54 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:38:54 INFO - 'talos_options': ['--disable-stylo'], 15:38:54 INFO - 'tests': ['damp', 'tps']}, 15:38:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:38:54 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:38:54 INFO - 'tests': ['dromaeo_dom']}, 15:38:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:38:54 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:38:54 INFO - 'tests': ['basic_compositor_video', 15:38:54 INFO - 'glvideo']}, 15:38:54 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:38:54 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:38:54 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:38:54 INFO - 'talos_options': ['--disable-stylo'], 15:38:54 INFO - 'tests': ['ts_paint_webext', 15:38:54 INFO - 'tp5o_webext']}, 15:38:54 INFO - 'other-e10s': {'tests': ['a11yr', 15:38:54 INFO - 'ts_paint', 15:38:54 INFO - 'tpaint', 15:38:54 INFO - 'sessionrestore', 15:38:54 INFO - 'sessionrestore_many_windows', 15:38:54 INFO - 'sessionrestore_no_auto_restore', 15:38:54 INFO - 'tabpaint']}, 15:38:54 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:38:54 INFO - 'tests': ['a11yr', 15:38:54 INFO - 'ts_paint', 15:38:54 INFO - 'tpaint', 15:38:54 INFO - 'sessionrestore', 15:38:54 INFO - 'sessionrestore_many_windows', 15:38:54 INFO - 'sessionrestore_no_auto_restore', 15:38:54 INFO - 'tabpaint']}, 15:38:54 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:38:54 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 15:38:54 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:38:54 INFO - 'tests': ['perf_reftest_singletons']}, 15:38:54 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:38:54 INFO - 'tests': ['bloom_basic']}, 15:38:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:38:54 INFO - 'tsvgr_opacity', 15:38:54 INFO - 'tart', 15:38:54 INFO - 'tscrollx', 15:38:54 INFO - 'tsvg_static']}, 15:38:54 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:38:54 INFO - 'tests': ['tsvgx', 15:38:54 INFO - 'tsvgr_opacity', 15:38:54 INFO - 'tart', 15:38:54 INFO - 'tscrollx', 15:38:54 INFO - 'tsvg_static']}, 15:38:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:38:54 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:38:54 INFO - 'talos_options': ['--disable-stylo'], 15:38:54 INFO - 'tests': ['tp5o']}, 15:38:54 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:38:54 INFO - 'talos_options': ['--mitmproxy', 15:38:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:38:54 INFO - '--firstNonBlankPaint'], 15:38:54 INFO - 'tests': ['tp6_google', 15:38:54 INFO - 'tp6_youtube', 15:38:54 INFO - 'tp6_amazon', 15:38:54 INFO - 'tp6_facebook']}, 15:38:54 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:38:54 INFO - 'talos_options': ['--disable-stylo', 15:38:54 INFO - '--mitmproxy', 15:38:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:38:54 INFO - '--firstNonBlankPaint'], 15:38:54 INFO - 'tests': ['tp6_google', 15:38:54 INFO - 'tp6_youtube', 15:38:54 INFO - 'tp6_amazon', 15:38:54 INFO - 'tp6_facebook']}, 15:38:54 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:38:54 INFO - 'talos_options': ['--stylo-threads=1', 15:38:54 INFO - '--mitmproxy', 15:38:54 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:38:54 INFO - '--firstNonBlankPaint'], 15:38:54 INFO - 'tests': ['tp6_google', 15:38:54 INFO - 'tp6_youtube', 15:38:54 INFO - 'tp6_amazon', 15:38:54 INFO - 'tp6_facebook']}, 15:38:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:38:54 INFO - 'talos_options': ['--xperf_path', 15:38:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:38:54 INFO - 'tests': ['tp5n']}, 15:38:54 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:38:54 INFO - 'talos_options': ['--disable-stylo', 15:38:54 INFO - '--xperf_path', 15:38:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:38:54 INFO - 'tests': ['tp5n']}}} 15:38:54 INFO - Running post-action listener: _resource_record_post_action 15:38:54 INFO - [mozharness: 2017-09-19 22:38:54.095175Z] Finished populate-webroot step (success) 15:38:54 INFO - [mozharness: 2017-09-19 22:38:54.095264Z] Running create-virtualenv step. 15:38:54 INFO - Running pre-action listener: _resource_record_pre_action 15:38:54 INFO - Running main action method: create_virtualenv 15:38:54 INFO - Creating virtualenv /builds/slave/test/build/venv 15:38:54 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:38:54 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:38:54 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:38:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:38:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:38:54 INFO - Installing distribute.............................................................................................................................................................................................done. 15:38:55 INFO - Installing pip...............done. 15:38:55 INFO - Return code: 0 15:38:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:38:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:38:55 INFO - Reading from file tmpfile_stdout 15:38:55 INFO - Output received: 15:38:55 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:38:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:38:55 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')]} 15:38:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:55 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')]} 15:38:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:55 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 0x19451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5090>, '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 0x1cb5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caff90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', '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 15:38:55 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 15:38:55 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 15:38:55 INFO - Using env: {'DISPLAY': ':0', 15:38:55 INFO - 'HOME': '/home/cltbld', 15:38:55 INFO - 'LANG': 'en_US.UTF-8', 15:38:55 INFO - 'LANGUAGE': 'en_US:en', 15:38:55 INFO - 'LOGNAME': 'cltbld', 15:38:55 INFO - 'MAIL': '/var/mail/cltbld', 15:38:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:55 INFO - 'MOZ_NO_REMOTE': '1', 15:38:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:55 INFO - 'NO_EM_RESTART': '1', 15:38:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:55 INFO - 'PWD': '/builds/slave/test', 15:38:55 INFO - 'SHELL': '/bin/bash', 15:38:55 INFO - 'SHLVL': '1', 15:38:55 INFO - 'TERM': 'linux', 15:38:55 INFO - 'TMOUT': '86400', 15:38:55 INFO - 'USER': 'cltbld', 15:38:55 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', 15:38:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:55 INFO - '_': '/tools/buildbot/bin/python'} 15:38:56 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:38:56 INFO - Downloading/unpacking pip>=1.5 15:38:56 INFO - Running setup.py egg_info for package pip 15:38:56 INFO - warning: no files found matching 'pip/cacert.pem' 15:38:56 INFO - warning: no files found matching '*.html' under directory 'docs' 15:38:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:38:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:38:56 INFO - Installing collected packages: pip 15:38:56 INFO - Found existing installation: pip 1.1 15:38:56 INFO - Uninstalling pip: 15:38:56 INFO - Successfully uninstalled pip 15:38:56 INFO - Running setup.py install for pip 15:38:56 INFO - warning: no files found matching 'pip/cacert.pem' 15:38:56 INFO - warning: no files found matching '*.html' under directory 'docs' 15:38:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:38:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:38:56 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:38:56 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:38:56 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:38:56 INFO - Successfully installed pip 15:38:56 INFO - Cleaning up... 15:38:56 INFO - Return code: 0 15:38:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:38:56 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')]} 15:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:56 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')]} 15:38:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:56 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 0x19451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5090>, '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 0x1cb5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caff90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', '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 15:38:56 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 15:38:56 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 15:38:56 INFO - Using env: {'DISPLAY': ':0', 15:38:56 INFO - 'HOME': '/home/cltbld', 15:38:56 INFO - 'LANG': 'en_US.UTF-8', 15:38:56 INFO - 'LANGUAGE': 'en_US:en', 15:38:56 INFO - 'LOGNAME': 'cltbld', 15:38:56 INFO - 'MAIL': '/var/mail/cltbld', 15:38:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:56 INFO - 'MOZ_NO_REMOTE': '1', 15:38:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:56 INFO - 'NO_EM_RESTART': '1', 15:38:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:56 INFO - 'PWD': '/builds/slave/test', 15:38:56 INFO - 'SHELL': '/bin/bash', 15:38:56 INFO - 'SHLVL': '1', 15:38:56 INFO - 'TERM': 'linux', 15:38:56 INFO - 'TMOUT': '86400', 15:38:56 INFO - 'USER': 'cltbld', 15:38:56 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', 15:38:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:56 INFO - '_': '/tools/buildbot/bin/python'} 15:38:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:58 INFO - Downloading/unpacking psutil>=3.1.1 15:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:38:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:38:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:38:59 INFO - Installing collected packages: psutil 15:38:59 INFO - Running setup.py install for psutil 15:38:59 INFO - building 'psutil._psutil_linux' extension 15:38:59 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:39:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:39:00 INFO - building 'psutil._psutil_posix' extension 15:39:00 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:39:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:39:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:00 INFO - Successfully installed psutil 15:39:00 INFO - Cleaning up... 15:39:00 INFO - Return code: 0 15:39:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:39:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5090>, '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 0x1cb5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caff90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', '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 15:39:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:39:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:39:00 INFO - Using env: {'DISPLAY': ':0', 15:39:00 INFO - 'HOME': '/home/cltbld', 15:39:00 INFO - 'LANG': 'en_US.UTF-8', 15:39:00 INFO - 'LANGUAGE': 'en_US:en', 15:39:00 INFO - 'LOGNAME': 'cltbld', 15:39:00 INFO - 'MAIL': '/var/mail/cltbld', 15:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:00 INFO - 'MOZ_NO_REMOTE': '1', 15:39:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:00 INFO - 'NO_EM_RESTART': '1', 15:39:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:00 INFO - 'PWD': '/builds/slave/test', 15:39:00 INFO - 'SHELL': '/bin/bash', 15:39:00 INFO - 'SHLVL': '1', 15:39:00 INFO - 'TERM': 'linux', 15:39:00 INFO - 'TMOUT': '86400', 15:39:00 INFO - 'USER': 'cltbld', 15:39:00 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', 15:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:00 INFO - '_': '/tools/buildbot/bin/python'} 15:39:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:39:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:39:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:39:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:39:02 INFO - Installing collected packages: mozsystemmonitor 15:39:02 INFO - Running setup.py install for mozsystemmonitor 15:39:02 INFO - Successfully installed mozsystemmonitor 15:39:02 INFO - Cleaning up... 15:39:02 INFO - Return code: 0 15:39:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:39:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:02 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 0x19451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5090>, '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 0x1cb5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caff90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', '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 15:39:02 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 15:39:02 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 15:39:02 INFO - Using env: {'DISPLAY': ':0', 15:39:02 INFO - 'HOME': '/home/cltbld', 15:39:02 INFO - 'LANG': 'en_US.UTF-8', 15:39:02 INFO - 'LANGUAGE': 'en_US:en', 15:39:02 INFO - 'LOGNAME': 'cltbld', 15:39:02 INFO - 'MAIL': '/var/mail/cltbld', 15:39:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:02 INFO - 'MOZ_NO_REMOTE': '1', 15:39:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:02 INFO - 'NO_EM_RESTART': '1', 15:39:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:02 INFO - 'PWD': '/builds/slave/test', 15:39:02 INFO - 'SHELL': '/bin/bash', 15:39:02 INFO - 'SHLVL': '1', 15:39:02 INFO - 'TERM': 'linux', 15:39:02 INFO - 'TMOUT': '86400', 15:39:02 INFO - 'USER': 'cltbld', 15:39:02 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', 15:39:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:02 INFO - '_': '/tools/buildbot/bin/python'} 15:39:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:02 INFO - Downloading/unpacking jsonschema==2.5.1 15:39:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:39:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:03 INFO - Downloading functools32-3.2.3-2.tar.gz 15:39:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:39:03 INFO - warning: no files found matching '*.txt' 15:39:03 INFO - no previously-included directories found matching 'build' 15:39:03 INFO - no previously-included directories found matching 'dist' 15:39:03 INFO - no previously-included directories found matching '.git*' 15:39:03 INFO - Installing collected packages: jsonschema, functools32 15:39:04 INFO - Running setup.py install for functools32 15:39:04 INFO - warning: no files found matching '*.txt' 15:39:04 INFO - no previously-included directories found matching 'build' 15:39:04 INFO - no previously-included directories found matching 'dist' 15:39:04 INFO - no previously-included directories found matching '.git*' 15:39:04 INFO - Successfully installed jsonschema functools32 15:39:04 INFO - Cleaning up... 15:39:04 INFO - Return code: 0 15:39:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:39:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5090>, '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 0x1cb5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caff90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', '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 15:39:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:39:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:39:04 INFO - Using env: {'DISPLAY': ':0', 15:39:04 INFO - 'HOME': '/home/cltbld', 15:39:04 INFO - 'LANG': 'en_US.UTF-8', 15:39:04 INFO - 'LANGUAGE': 'en_US:en', 15:39:04 INFO - 'LOGNAME': 'cltbld', 15:39:04 INFO - 'MAIL': '/var/mail/cltbld', 15:39:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:04 INFO - 'MOZ_NO_REMOTE': '1', 15:39:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:04 INFO - 'NO_EM_RESTART': '1', 15:39:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:04 INFO - 'PWD': '/builds/slave/test', 15:39:04 INFO - 'SHELL': '/bin/bash', 15:39:04 INFO - 'SHLVL': '1', 15:39:04 INFO - 'TERM': 'linux', 15:39:04 INFO - 'TMOUT': '86400', 15:39:04 INFO - 'USER': 'cltbld', 15:39:04 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', 15:39:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:04 INFO - '_': '/tools/buildbot/bin/python'} 15:39:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:39:04 INFO - Cleaning up... 15:39:04 INFO - Return code: 0 15:39:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:39:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5090>, '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 0x1cb5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caff90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', '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 15:39:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:39:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:39:04 INFO - Using env: {'DISPLAY': ':0', 15:39:04 INFO - 'HOME': '/home/cltbld', 15:39:04 INFO - 'LANG': 'en_US.UTF-8', 15:39:04 INFO - 'LANGUAGE': 'en_US:en', 15:39:04 INFO - 'LOGNAME': 'cltbld', 15:39:04 INFO - 'MAIL': '/var/mail/cltbld', 15:39:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:04 INFO - 'MOZ_NO_REMOTE': '1', 15:39:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:04 INFO - 'NO_EM_RESTART': '1', 15:39:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:04 INFO - 'PWD': '/builds/slave/test', 15:39:04 INFO - 'SHELL': '/bin/bash', 15:39:04 INFO - 'SHLVL': '1', 15:39:04 INFO - 'TERM': 'linux', 15:39:04 INFO - 'TMOUT': '86400', 15:39:04 INFO - 'USER': 'cltbld', 15:39:04 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', 15:39:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:04 INFO - '_': '/tools/buildbot/bin/python'} 15:39:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:04 INFO - Downloading/unpacking blobuploader==1.2.4 15:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:05 INFO - Downloading blobuploader-1.2.4.tar.gz 15:39:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:39:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:39:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:39:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:39:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:06 INFO - Downloading docopt-0.6.1.tar.gz 15:39:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:39:06 INFO - Installing collected packages: blobuploader, requests, docopt 15:39:06 INFO - Running setup.py install for blobuploader 15:39:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:39:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:39:06 INFO - Running setup.py install for requests 15:39:06 INFO - Running setup.py install for docopt 15:39:06 INFO - Successfully installed blobuploader requests docopt 15:39:06 INFO - Cleaning up... 15:39:06 INFO - Return code: 0 15:39:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5090>, '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 0x1cb5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caff90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', '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 15:39:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:39:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:39:06 INFO - Using env: {'DISPLAY': ':0', 15:39:06 INFO - 'HOME': '/home/cltbld', 15:39:06 INFO - 'LANG': 'en_US.UTF-8', 15:39:06 INFO - 'LANGUAGE': 'en_US:en', 15:39:06 INFO - 'LOGNAME': 'cltbld', 15:39:06 INFO - 'MAIL': '/var/mail/cltbld', 15:39:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:06 INFO - 'MOZ_NO_REMOTE': '1', 15:39:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:06 INFO - 'NO_EM_RESTART': '1', 15:39:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:06 INFO - 'PWD': '/builds/slave/test', 15:39:06 INFO - 'SHELL': '/bin/bash', 15:39:06 INFO - 'SHLVL': '1', 15:39:06 INFO - 'TERM': 'linux', 15:39:06 INFO - 'TMOUT': '86400', 15:39:06 INFO - 'USER': 'cltbld', 15:39:06 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', 15:39:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:06 INFO - '_': '/tools/buildbot/bin/python'} 15:39:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:07 INFO - Running setup.py (path:/tmp/pip-NTJnOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:07 INFO - Running setup.py (path:/tmp/pip-efdEUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:39:07 INFO - Running setup.py (path:/tmp/pip-7nCglf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:39:07 INFO - Running setup.py (path:/tmp/pip-F30Mm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:39:07 INFO - Running setup.py (path:/tmp/pip-nWw2zi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:39:07 INFO - Running setup.py (path:/tmp/pip-mYS9VS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:39:07 INFO - Running setup.py (path:/tmp/pip-1TvH0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:39:07 INFO - Running setup.py (path:/tmp/pip-DDTjJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:39:07 INFO - Running setup.py (path:/tmp/pip-ahsHZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:39:07 INFO - Running setup.py (path:/tmp/pip-qQLGKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:39:07 INFO - Running setup.py (path:/tmp/pip-rqg_ek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:39:07 INFO - Running setup.py (path:/tmp/pip-eLUikY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:39:07 INFO - Running setup.py (path:/tmp/pip-cNVqzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:39:08 INFO - Running setup.py (path:/tmp/pip-zcserx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:08 INFO - Running setup.py (path:/tmp/pip-E3WkLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:39:08 INFO - Running setup.py (path:/tmp/pip-PJr9ko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:39:08 INFO - Running setup.py (path:/tmp/pip-ADsTgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:39:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:39:08 INFO - Running setup.py install for manifestparser 15:39:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:39:08 INFO - Running setup.py install for mozcrash 15:39:08 INFO - Running setup.py install for mozdebug 15:39:08 INFO - Running setup.py install for mozdevice 15:39:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:39:08 INFO - Running setup.py install for mozfile 15:39:08 INFO - Running setup.py install for mozhttpd 15:39:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:39:08 INFO - Running setup.py install for mozinfo 15:39:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:39:08 INFO - Running setup.py install for mozInstall 15:39:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:39:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:39:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:39:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:39:09 INFO - Running setup.py install for mozleak 15:39:09 INFO - Running setup.py install for mozlog 15:39:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:39:09 INFO - Running setup.py install for moznetwork 15:39:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:39:09 INFO - Running setup.py install for mozprocess 15:39:09 INFO - Running setup.py install for mozprofile 15:39:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:39:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:39:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:39:09 INFO - Running setup.py install for mozrunner 15:39:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:39:09 INFO - Running setup.py install for mozscreenshot 15:39:09 INFO - Running setup.py install for moztest 15:39:09 INFO - Running setup.py install for mozversion 15:39:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:39:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:39:10 INFO - Cleaning up... 15:39:10 INFO - Return code: 0 15:39:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:10 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 0x19451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5090>, '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 0x1cb5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caff90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', '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 15:39:10 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 15:39:10 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 15:39:10 INFO - Using env: {'DISPLAY': ':0', 15:39:10 INFO - 'HOME': '/home/cltbld', 15:39:10 INFO - 'LANG': 'en_US.UTF-8', 15:39:10 INFO - 'LANGUAGE': 'en_US:en', 15:39:10 INFO - 'LOGNAME': 'cltbld', 15:39:10 INFO - 'MAIL': '/var/mail/cltbld', 15:39:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:10 INFO - 'MOZ_NO_REMOTE': '1', 15:39:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:10 INFO - 'NO_EM_RESTART': '1', 15:39:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:10 INFO - 'PWD': '/builds/slave/test', 15:39:10 INFO - 'SHELL': '/bin/bash', 15:39:10 INFO - 'SHLVL': '1', 15:39:10 INFO - 'TERM': 'linux', 15:39:10 INFO - 'TMOUT': '86400', 15:39:10 INFO - 'USER': 'cltbld', 15:39:10 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', 15:39:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:10 INFO - '_': '/tools/buildbot/bin/python'} 15:39:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:10 INFO - Running setup.py (path:/tmp/pip-zWP6uY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:10 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)) 15:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:10 INFO - Running setup.py (path:/tmp/pip-wt3Hma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:39:10 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)) 15:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:39:10 INFO - Running setup.py (path:/tmp/pip-KwnbT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:39:10 INFO - Running setup.py (path:/tmp/pip-XBXdQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:39:10 INFO - Running setup.py (path:/tmp/pip-DDM206-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:39:10 INFO - Running setup.py (path:/tmp/pip-rkWs1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:39:10 INFO - Running setup.py (path:/tmp/pip-zTU820-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:39:10 INFO - Running setup.py (path:/tmp/pip-hNw7i9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:39:10 INFO - Running setup.py (path:/tmp/pip-183koj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:39:10 INFO - Running setup.py (path:/tmp/pip-gg1BTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:39:10 INFO - Running setup.py (path:/tmp/pip-fhggUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:39:11 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)) 15:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:39:11 INFO - Running setup.py (path:/tmp/pip-Dl3ygB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:39:11 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)) 15:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:39:11 INFO - Running setup.py (path:/tmp/pip-wL5I8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:39:11 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)) 15:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:39:11 INFO - Running setup.py (path:/tmp/pip-ZrKy5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:39:11 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)) 15:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:11 INFO - Running setup.py (path:/tmp/pip-Fxwv9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:11 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)) 15:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:39:11 INFO - Running setup.py (path:/tmp/pip-l5PteN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:39:11 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)) 15:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:39:11 INFO - Running setup.py (path:/tmp/pip-rGg9MH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:39:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:39:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:39:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:39:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:39:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:39:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:39:11 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:39:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:12 INFO - Downloading blessings-1.6.tar.gz 15:39:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:39:12 INFO - Installing collected packages: blessings 15:39:12 INFO - Running setup.py install for blessings 15:39:12 INFO - Successfully installed blessings 15:39:12 INFO - Cleaning up... 15:39:12 INFO - Return code: 0 15:39:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:39:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:39:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:39:13 INFO - Reading from file tmpfile_stdout 15:39:13 INFO - Current package versions: 15:39:13 INFO - argparse == 1.2.1 15:39:13 INFO - blessings == 1.6 15:39:13 INFO - blobuploader == 1.2.4 15:39:13 INFO - docopt == 0.6.1 15:39:13 INFO - functools32 == 3.2.3-2 15:39:13 INFO - jsonschema == 2.5.1 15:39:13 INFO - manifestparser == 1.1 15:39:13 INFO - mozInstall == 1.13 15:39:13 INFO - mozcrash == 1.0 15:39:13 INFO - mozdebug == 0.1 15:39:13 INFO - mozdevice == 0.51 15:39:13 INFO - mozfile == 1.2 15:39:13 INFO - mozhttpd == 0.7 15:39:13 INFO - mozinfo == 0.10 15:39:13 INFO - mozleak == 0.1 15:39:13 INFO - mozlog == 3.5 15:39:13 INFO - moznetwork == 0.27 15:39:13 INFO - mozprocess == 0.25 15:39:13 INFO - mozprofile == 0.28 15:39:13 INFO - mozrunner == 6.13 15:39:13 INFO - mozscreenshot == 0.1 15:39:13 INFO - mozsystemmonitor == 0.3 15:39:13 INFO - moztest == 0.8 15:39:13 INFO - mozversion == 1.4 15:39:13 INFO - psutil == 3.1.1 15:39:13 INFO - requests == 1.2.3 15:39:13 INFO - wsgiref == 0.1.2 15:39:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:13 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 0x19451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5090>, '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 0x1cb5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caff90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', '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 15:39:13 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 15:39:13 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 15:39:13 INFO - Using env: {'DISPLAY': ':0', 15:39:13 INFO - 'HOME': '/home/cltbld', 15:39:13 INFO - 'LANG': 'en_US.UTF-8', 15:39:13 INFO - 'LANGUAGE': 'en_US:en', 15:39:13 INFO - 'LOGNAME': 'cltbld', 15:39:13 INFO - 'MAIL': '/var/mail/cltbld', 15:39:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:13 INFO - 'MOZ_NO_REMOTE': '1', 15:39:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:13 INFO - 'NO_EM_RESTART': '1', 15:39:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:13 INFO - 'PWD': '/builds/slave/test', 15:39:13 INFO - 'SHELL': '/bin/bash', 15:39:13 INFO - 'SHLVL': '1', 15:39:13 INFO - 'TERM': 'linux', 15:39:13 INFO - 'TMOUT': '86400', 15:39:13 INFO - 'USER': 'cltbld', 15:39:13 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', 15:39:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:13 INFO - '_': '/tools/buildbot/bin/python'} 15:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:13 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)) 15:39:13 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)) 15:39:13 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)) 15:39:13 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)) 15:39:13 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)) 15:39:13 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)) 15:39:13 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)) 15:39:13 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)) 15:39:13 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)) 15:39:13 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:39:13 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 15:39:13 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 15:39:13 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 15:39:13 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 15:39:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:39:14 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)) 15:39:14 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)) 15:39:14 INFO - Installing collected packages: simplejson 15:39:14 INFO - Running setup.py install for simplejson 15:39:14 INFO - building 'simplejson._speedups' extension 15:39:14 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 15:39:15 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 15:39:15 INFO - Successfully installed simplejson 15:39:15 INFO - Cleaning up... 15:39:15 INFO - Return code: 0 15:39:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:39:15 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')]} 15:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:15 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')]} 15:39:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:15 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 0x19451f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b0ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb1a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5090>, '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 0x1cb5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caff90>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', '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 15:39:15 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 15:39:15 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 15:39:15 INFO - Using env: {'DISPLAY': ':0', 15:39:15 INFO - 'HOME': '/home/cltbld', 15:39:15 INFO - 'LANG': 'en_US.UTF-8', 15:39:15 INFO - 'LANGUAGE': 'en_US:en', 15:39:15 INFO - 'LOGNAME': 'cltbld', 15:39:15 INFO - 'MAIL': '/var/mail/cltbld', 15:39:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:15 INFO - 'MOZ_NO_REMOTE': '1', 15:39:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:15 INFO - 'NO_EM_RESTART': '1', 15:39:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:15 INFO - 'PWD': '/builds/slave/test', 15:39:15 INFO - 'SHELL': '/bin/bash', 15:39:15 INFO - 'SHLVL': '1', 15:39:15 INFO - 'TERM': 'linux', 15:39:15 INFO - 'TMOUT': '86400', 15:39:15 INFO - 'USER': 'cltbld', 15:39:15 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', 15:39:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:15 INFO - '_': '/tools/buildbot/bin/python'} 15:39:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:39:15 INFO - Cleaning up... 15:39:15 INFO - Return code: 0 15:39:15 INFO - Running post-action listener: _resource_record_post_action 15:39:15 INFO - Running post-action listener: _start_resource_monitoring 15:39:15 INFO - Starting resource monitoring. 15:39:15 INFO - [mozharness: 2017-09-19 22:39:15.835462Z] Finished create-virtualenv step (success) 15:39:15 INFO - [mozharness: 2017-09-19 22:39:15.836470Z] Running install step. 15:39:15 INFO - Running pre-action listener: _resource_record_pre_action 15:39:15 INFO - Running main action method: install 15:39:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:39:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:39:16 INFO - Reading from file tmpfile_stdout 15:39:16 INFO - Detecting whether we're running mozinstall >=1.0... 15:39:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:39:16 INFO - Reading from file tmpfile_stdout 15:39:16 INFO - Output received: 15:39:16 INFO - Usage: mozinstall [options] installer 15:39:16 INFO - Options: 15:39:16 INFO - -h, --help show this help message and exit 15:39:16 INFO - -d DEST, --destination=DEST 15:39:16 INFO - Directory to install application into. [default: 15:39:16 INFO - "/builds/slave/test"] 15:39:16 INFO - --app=APP Application being installed. [default: firefox] 15:39:16 INFO - mkdir: /builds/slave/test/build/application 15:39:16 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'] 15:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:39:33 INFO - Reading from file tmpfile_stdout 15:39:33 INFO - Output received: 15:39:33 INFO - /builds/slave/test/build/application/firefox/firefox 15:39:33 INFO - Running post-action listener: _resource_record_post_action 15:39:33 INFO - [mozharness: 2017-09-19 22:39:33.979135Z] Finished install step (success) 15:39:33 INFO - [mozharness: 2017-09-19 22:39:33.979388Z] Running setup-mitmproxy step. 15:39:33 INFO - Running pre-action listener: _resource_record_pre_action 15:39:33 INFO - Running main action method: setup_mitmproxy 15:39:33 INFO - Skipping: mitmproxy is not required 15:39:33 INFO - Running post-action listener: _resource_record_post_action 15:39:33 INFO - [mozharness: 2017-09-19 22:39:33.980528Z] Finished setup-mitmproxy step (success) 15:39:33 INFO - [mozharness: 2017-09-19 22:39:33.980750Z] Running run-tests step. 15:39:33 INFO - Running pre-action listener: _resource_record_pre_action 15:39:33 INFO - Running pre-action listener: _set_gcov_prefix 15:39:33 INFO - Running main action method: run_tests 15:39:33 WARNING - Try message not found. 15:39:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:39:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:39:33 INFO - Python 2.7.3 15:39:33 INFO - Return code: 0 15:39:33 INFO - grabbing minidump binary from tooltool 15:39:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:33 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 0x1cb5090>, '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 0x1cb5d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1caff90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:39:33 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 15:39:33 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 15:39:33 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 15:39:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:39:34 INFO - Return code: 0 15:39:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:39:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:39:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:39:34 INFO - ENV: RUST_BACKTRACE is now full 15:39:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:39:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:39:34 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/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 15:39:34 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/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 15:39:34 INFO - Using env: {'DISPLAY': ':0', 15:39:34 INFO - 'HOME': '/home/cltbld', 15:39:34 INFO - 'LANG': 'en_US.UTF-8', 15:39:34 INFO - 'LANGUAGE': 'en_US:en', 15:39:34 INFO - 'LOGNAME': 'cltbld', 15:39:34 INFO - 'MAIL': '/var/mail/cltbld', 15:39:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:39:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:39:34 INFO - 'MOZ_ACCELERATED': '1', 15:39:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:34 INFO - 'MOZ_NO_REMOTE': '1', 15:39:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:39:34 INFO - 'MOZ_WEBRENDER': '1', 15:39:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:34 INFO - 'NO_EM_RESTART': '1', 15:39:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:34 INFO - 'PWD': '/builds/slave/test', 15:39:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:39:34 INFO - 'RUST_BACKTRACE': 'full', 15:39:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:39:34 INFO - 'SHELL': '/bin/bash', 15:39:34 INFO - 'SHLVL': '1', 15:39:34 INFO - 'TERM': 'linux', 15:39:34 INFO - 'TMOUT': '86400', 15:39:34 INFO - 'USER': 'cltbld', 15:39:34 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', 15:39:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:34 INFO - '_': '/tools/buildbot/bin/python'} 15:39:34 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/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 15:39:34 INFO - mozversion application_buildid: 20170919214925 15:39:34 INFO - mozversion application_changeset: a0eb21bf55e1c1ae0ba311e6f2273da05c712799 15:39:34 INFO - mozversion application_display_name: Nightly 15:39:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:39:34 INFO - mozversion application_name: Firefox 15:39:34 INFO - mozversion application_remotingname: firefox 15:39:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:39:34 INFO - mozversion application_vendor: Mozilla 15:39:34 INFO - mozversion application_version: 57.0a1 15:39:34 INFO - mozversion platform_buildid: 20170919214925 15:39:34 INFO - mozversion platform_changeset: a0eb21bf55e1c1ae0ba311e6f2273da05c712799 15:39:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:39:34 INFO - mozversion platform_version: 57.0a1 15:39:34 INFO - using testdate: 1505860774 15:39:34 INFO - actual date: 1505860774 15:39:34 INFO - starting webserver on 'localhost:33924' 15:39:34 INFO - SUITE-START | Running 2 tests 15:39:34 INFO - TEST-START | dromaeo_css 15:39:34 INFO - Initialising browser for dromaeo_css test... 15:39:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33924/getInfo.html -profile /tmp/tmpyEW2Ee/profile 15:39:34 INFO - TEST-INFO | started process 3514 (/builds/slave/test/build/application/firefox/firefox http://localhost:33924/getInfo.html) 15:39:43 INFO - TEST-INFO | 3514: exit 0 15:39:44 INFO - Browser initialized. 15:39:44 INFO - Running cycle 1/1 for dromaeo_css test... 15:39:44 INFO - Using env: {'DISPLAY': ':0', 15:39:44 INFO - 'HOME': '/home/cltbld', 15:39:44 INFO - 'JSGC_DISABLE_POISONING': '1', 15:39:44 INFO - 'LANG': 'en_US.UTF-8', 15:39:44 INFO - 'LANGUAGE': 'en_US:en', 15:39:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:39:44 INFO - 'LOGNAME': 'cltbld', 15:39:44 INFO - 'MAIL': '/var/mail/cltbld', 15:39:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:39:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:39:44 INFO - 'MOZ_ACCELERATED': '1', 15:39:44 INFO - 'MOZ_CRASHREPORTER': '1', 15:39:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:39:44 INFO - 'MOZ_NO_REMOTE': '1', 15:39:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:39:44 INFO - 'MOZ_WEBRENDER': '1', 15:39:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:44 INFO - 'NO_EM_RESTART': '1', 15:39:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:44 INFO - 'PWD': '/builds/slave/test', 15:39:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:39:44 INFO - 'RUST_BACKTRACE': 'full', 15:39:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:39:44 INFO - 'SHELL': '/bin/bash', 15:39:44 INFO - 'SHLVL': '1', 15:39:44 INFO - 'TERM': 'linux', 15:39:44 INFO - 'TMOUT': '86400', 15:39:44 INFO - 'USER': 'cltbld', 15:39:44 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', 15:39:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:44 INFO - '_': '/tools/buildbot/bin/python'} 15:39:44 INFO - TEST-INFO | started process 3753 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyEW2Ee/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:39:44 INFO - PID 3753 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:39:45 INFO - PID 3753 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:39:45 INFO - PID 3753 | 15:39:45 INFO - PID 3753 | (/builds/slave/test/build/application/firefox/firefox:3812): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:39:45 INFO - PID 3753 | 15:39:55 INFO - PID 3753 | RSS: Main: 203706368 15:39:55 INFO - PID 3753 | 15:42:53 INFO - PID 3753 | Cycle 1(1): loaded http://localhost:33924/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33924/tests/dromaeo/cssquery-ext.html) 15:42:54 INFO - PID 3753 | RSS: Main: 206401536 15:42:54 INFO - PID 3753 | 15:45:57 INFO - PID 3753 | Cycle 1(1): loaded http://localhost:33924/tests/dromaeo/cssquery-ext.html (next: http://localhost:33924/tests/dromaeo/cssquery-jquery.html) 15:45:57 INFO - PID 3753 | RSS: Main: 206790656 15:45:57 INFO - PID 3753 | 15:48:59 INFO - PID 3753 | Cycle 1(1): loaded http://localhost:33924/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33924/tests/dromaeo/cssquery-mootools.html) 15:49:00 INFO - PID 3753 | RSS: Main: 209170432 15:49:00 INFO - PID 3753 | 15:52:02 INFO - PID 3753 | Cycle 1(1): loaded http://localhost:33924/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33924/tests/dromaeo/cssquery-prototype.html) 15:52:03 INFO - PID 3753 | RSS: Main: 206999552 15:52:03 INFO - PID 3753 | 15:55:07 INFO - PID 3753 | Cycle 1(1): loaded http://localhost:33924/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33924/tests/dromaeo/cssquery-yui.html) 15:55:07 INFO - PID 3753 | RSS: Main: 209063936 15:55:07 INFO - PID 3753 | 15:58:09 INFO - PID 3753 | Cycle 1(1): loaded http://localhost:33924/tests/dromaeo/cssquery-yui.html (next: http://localhost:33924/tests/dromaeo/cssquery-dojo.html) 15:58:09 INFO - PID 3753 | RSS: Main: 211726336 15:58:09 INFO - PID 3753 | 15:58:09 INFO - PID 3753 | __start_tp_report 15:58:09 INFO - PID 3753 | _x_x_mozilla_page_load 15:58:09 INFO - PID 3753 | _x_x_mozilla_page_load_details 15:58:09 INFO - PID 3753 | |i|pagename|runs| 15:58:09 INFO - PID 3753 | |0;dojo.html;3084.70695283948;3110.0669799060274;3133.7179653831163;3137.513685378767;3153.4008538377707;6192.225971753531;6373.426391624753;6386.521010924182;6420.935790642092;6429.292777794439;6201.53488488364;6276.748930042792;6305.936940630592;6324.209473815773;6360.395762402564;5785.27684039495;5786.913196302048;5787.971060144693;5836.416358364172;5869.119632055204;5896.056630939051;5925.4370834770625;5992;5999.790007349744;6001.2498437695285;5824.184614154022;5854.973173344144;5856.502197313233;5901.05583106703;5932.2584676915385;973.5754072315902;5231.843044708627;5605.691686957216;5707.200991861143;5742.28221472316;5788.826335209908;5795.507381872546;5831.037878750001;5834.037383831663;5892.882142357129;6136.294326152505;6260.09228661846;6281.24625044993;6309.9220732601225;6395.456386207178;6681.53229273951;6752.797416077526;6825.419839313615;6855.4172895303955;6866.107406037183;6780.254172041072;6782.152230971129;6868.381845633867;6871;6883.070785443951;6092.847678808021;6271.905921411182;6298.086777417295;6309.242890853127;6312.242530896322;7051.612161331073;7060.293970602898;7070.469714771412;7093.077899872984;7095.503314767917;6714.932850671528;6766.594004359656;6767.560108592926;6794.11676482064;6795.082663840417;6808.285130061371;6815.795526134224;6830.829229269308;6905.792826215222;6909.550879192937;6494;6768.187817461838;6770.289119642367;6808.8978665320055;6862.656867156622;6676.83307917306;6685.866282674319;6692.665366731644;6695.296993815579;6701.865962680719;5277.868053298698;5282.181261904334;5330.8933821324135;5399.163129714901;5412;4688.765561721901;4690.413698287714;4693.859184224479;4705.106029854385;4748.335233067373;5791.5656325775735;5805.709714514257;5921.644701317935;5926.911096333559;5928.584999050111;6505.349465053551;6785.185777706608;6787.083743694538;6829.4877884157895;6881.071055407456;5739.569532285095;5787.797427090116;5793.131030345482;5849.210356601804;5857.238558987305;5836.533077353802;5849.590528662953;5864.882702345929;5919.4968427684125;5925.1704761332585;6547.985062315349;6558.376954189344;6621.271660117383;6621.437177839841;6645.169353830771;6828.760993365208;6833.2483426824;6837.076994605664;6917.757878474229;6951.513394062468;6359.109724638462;6495.675216239264;6510.18622672166;6558.114654521579;6587.176602924635;6627.502937279627;6740.258571557223;6746.291639377795;6760.08738820253;6767.25560188369;6805.081314022544;6893.310668933066;6893.7931862044215;6909.1018167637885;6923.480738944499;6583.111279977142;6591.934080659324;6605.504587155887;6679.899801503078;6698.531102822951;6464.806055818333;6561;6569.540132190701;6610.4711623071;6699.43054840344;6494.253160886369;6544.3782840630065;6553.672316384257;6579.539432239697;6635.303293154149;6663.800085997428;6712.429443497357;6782.626955517297;6789.762358317435;6813.7955861324235;6192.473639740672;6266.937330626818;6411.294757576569;6423.164988551458;6529.7388104475285 15:58:09 INFO - PID 3753 | |1;ext.html;769.4037121231049;780.0210735526915;784.5292824305418;789.6130895861027;790.7113903425247;39860.40209396858;40404;40484.59515404849;40569;40666.59333406665;37436.43845342322;37653.81173094131;37766.62233377666;37792.81103594478;38166.4275035874;6984.720611175547;7094;7097.18382386027;7103.928960710378;7154.105736782903;6903.930960690379;7089.610071446068;7089.71641134354;7096.183938847047;7113.502054856162;7068.540544864593;7086.964565177167;7113.395361394262;7767;7949.562774047454;7831.608419578998;7852.097008843999;7895.8026049348655;7910.7231246906085;7916.6833326666865;7804.2585954334245;7884;7906.7627971160955;7916.168802275735;7934.087579928324;37665.246695065944;37982.62017379846;38027;38132.23735525302;38273.425898611546;3390.9491357629654;3444.3111377724304;3446.638102999199;3446.965910226922;3448.689617934398;31181.8440907794;31314.373712525623;31371.37257254865;31486.37027259442;31487.212819679462;5236.92144617831;5252.816151434681;5270.077736396153;5275.57795376369;5283.762230699628;178106;178869;178905;179473;179972;41074.38388424176;41946.95132621709;41947;47226.05547889023;47869.28196077062;49578.25632615513;49681.50318496769;49788;49863;49948;14943.40226390954;14950.775738363933;15351.846481535265;15428.922855385876;15513.922430388002;12096.092793040558;12179.330136842382;12247.265164090182;12339.50641974311;12490.313032783284;13954.372053257628;14057.297135143202;14112.22382768937;14168.645783855485;14229;5636.210930469738;5716.599838011382;5729.627574207663;5823.213866128019;5823.826014821932;999.2555546118189;1008.1732978957185;1036.9637062702768;1038.2680210451613;1058.629479682105;23157.88421057884;23206.65190022151;23233.419164521023;23316.883415583154;23714.407139821644;1119.0879433261866;1122.5453691255054;1129.4409267412684;1138.5445821671233;1138.9886101138882;12495.625131246077;12496.437660305308;12650.430730617145;12762.553310634268;12810.9359453204;217394;218599;218663;219366;219373;52704;52915.206271905954;53017.73491132597;53087.73456132694;53171.73414132905;1400.9229492378017;1460.3355473259642;1466.3841186701611;1479.3047267784125;1480.5040311495554;22471;22935.082596695946;22947.082116715144;23185.420364491023;23211.651825222296;37151.256974860895;37336.813315934334;37374.252514950094;37390.06524836901;37427;187683;188588;188611;190563;190776;32633;32818.67181328156;32953.67046329506;33147.83426082854;33291.00126996194;40762;41281.58718412898;41753.373699393924;41955.58044419517;42078.15843683048;31160.37679246357;31305.686943130277;31329.53005704962;31381.215469613442;31425;30847.691523084482;31073.8446307767;31100.22249443782;31425.52861707122;31646.683533164374;34746.47880281747;35419.82290088533;35486.11284717903;35563.11092222715;35587.28825423529;29608.555871661512;29731.10806675803;29808.403831923675;30816.53775193419;31380.529292061095;22938.42653933665;23034.078636854338;23045.193418230283;23347.88326058359;23505.412364690335 15:58:09 INFO - PID 3753 | |2;jquery.html;572.2360648534207;575.8071046199523;576.2019602850054;579.9618682558219;581.4185814185814;21558;22461.21385751497;22468.775312246893;22545.32364029082;22549;22770.203042893503;23932;24088.638670419958;24358.512829743395;24396.999999999956;6427.196600424947;6646.202455705298;6648.268690444047;6648.601083934979;6663.800085997404;6416.069669897862;6419.518536109787;6486.545941784077;6533.509986751013;6535.215774107114;6205.534584906128;6413.7113829877535;6451.903221451682;6470.029495575654;6483.773067942623;6452.161219041518;6465;6473.643949582794;6496.187976502937;6503.089567460559;6006.96996515019;6018.3981601839905;6021.729022194012;6023.036314189752;6046.939530604682;27479.31301717473;27760.167194984184;28548.14355569336;28567.000154994475;28592.714072859213;1987.1653905359783;2061.865978711394;2063.988645563678;2065.215218217083;2065.483629092727;18213.817861821346;18216.08919554017;18234.452966410896;18318.450446486626;18339.633207335777;2326.4183954011496;2340.5318936212825;2393.085531574733;2411.9276421707377;2416.4683769570665;118885;122637.38681306626;122764.38617806943;129461.35269323687;138082.99999999898;18636.44090677282;20207.898960505103;20283.594328113355;20690.379288621367;21484.892575537335;40722.1855562893;40861;40900.795496022925;40987.59012409898;41062.38406423906;10936.398498082523;10979.83530247047;11093.611723589796;11146.275492092987;11263.155263355282;9205.585748641328;9239.445633262027;9329.580168892417;9401.717948461557;9430.858537121949;10741.677749667522;10757.408342541079;10889.401082940356;11264.324140551593;11373.829392559119;3471.7049050830456;3810.542734871833;3814.8664796731982;3841.750286231386;3972.4041393790585;935.3499317973943;954.7804005078732;968.7093871838421;971.9697121051707;981.622075500923;18740.53148671267;18829;19190.904045479685;19298.13158407875;19330.226790928486;993.5231089077283;1014.0569270578433;1022.5296363672645;1030.253066526777;1036.512838965685;10430.739231519272;10564.735881603021;10587.417692026858;10729.9463502682;10835.5913731216;424175.3303172792;441955;443826;450196;454497;107802.46098769456;108996;111736.4413177929;112457;113432.43283783528;1156.259993604094;1178.5816035307514;1230.6492649595002;1237.3194742891553;1239.6219153158193;19640.116194771555;19879.403617891487;19926.800731992495;20268.695969560762;20439.4890127748;53630.73184634052;54763.72618136884;55913.44086559083;56299.71850140723;56486.71756641355;63561.68219158875;63600;63883.36116638774;63973.36026639674;64682;13917;13955.441782328593;14124.081934674214;14197.503087391891;14227.217503037433;22002.229921952654;22194.334169974925;22203.666944995515;22324.10703571839;22558.661620075385;17226;17419.564510886823;17639.911800440917;17711.20299586496;17916.193771280585;15786.9210653946;15876.841231587536;16240;16254.862159649185;16409.91795041017;18283.451496455127;18302.542436439195;18358.1738821756;18381.448556543324;18393.81606183921;14012.789808152682;14037.789433158716;14055.648608784453;14061.296935153407;14068.155910645393;17544.561385964942;17613.559661008578;17690.292388304322;17741.64516709633;17844.19701113427 15:58:09 INFO - PID 3753 | |3;mootools.html;1239.41127964217;1298.5844529750477;1306.9215847049172;1312.7768279392503;1319.5051855554168;15200.619984500394;15678.137702426362;15784;15939.123348215844;16005.75991360129;15592.532224033268;15614.76577851333;15639.296231669574;15719.292631831566;15771.526854194364;2692.9461410771773;2699.500592390404;2784.554471284595;2797.482465743844;2800.565912283599;2663.547196976509;2690.3408664877134;2692.6364940732947;2695.649565556475;2697.366118962052;2269.375921621551;2655.8539280339573;2660.946781064378;2686.449277898036;2688.3413563676927;2568.511982723286;2728.6998430172566;2742.9588556171675;2765.5989881467076;2768.8477133757633;2644.352133727249;2656.495265899473;2670.319068637485;2672.438787854553;2682.2355628645937;14546.127232365987;14937.402503899832;14943.252837358088;15020.774688379683;15036.097834129987;8014;8330.333573314121;8361.331093512505;8411.285040771541;8412.915870841274;5402.35171779389;5645.350784659776;5696.6866822324755;5753.33836608787;5796.82609521715;1964.0948280168357;1965.4300252926635;1971.112999150388;2013.6778115501595;2024.8278896293832;24357.878210608833;26925.192244232705;26936;27035.729642703518;27302.317442063908;6235.750569977241;7416.555006699616;7445.106587209461;7698.846023079507;7706.38348932084;20290.898545507473;20422.183112675328;20451.79548204499;20492.69260961087;20494;8076.919230807616;8296.128906464855;8319.584020798935;8330.042045164762;8368.24685778283;7310;7341.70633174667;7401.444891633149;7531.623418829036;7545.056867891513;5511.862203444946;5606.131049687305;5625.38120806711;5642.379338272786;5698.116791897262;1791.9552011199823;1829.5151784776779;1840.8251216134445;1863.8229368209998;1885.415521188436;876.202655583416;908.1236606674458;910.7131253655077;914.7941713114496;918.4672889723944;7534.472586918863;7725.690972361042;7726.38188944883;7737.381009519225;7743.728969486041;689.1316940654812;695.3498478922224;698.249381914444;716.30160593421;721.4120491799167;5189.766460509286;5206.505381988705;5226.895462090737;5235.502627250406;5250.317458730417;20083.29708460226;20543.691844622343;21096.78903210979;21450.249241276793;21710;20690.17239310411;21227.257046003626;21290.680639790415;21357.25249616287;21572.4606884826;10850.131989440826;11379.146564007733;11423.200375973769;11423.714407139889;11575.826362604568;7455.515391499535;9047.457152570867;9061;9264.21254193374;9283.303752218746;17061.232244548777;17221.91389043097;17597.560060998578;17610.11949402499;17828.19773110239;17784.644307113525;18105;18965.241390344232;18988.816286127272;19076.236950521823;12233.266004039786;12338.629841104781;12361.752764944471;12427.130100892851;12447.315397653216;13765.242911639956;13938.790918136034;13961.511347102803;14050.227237502035;14066.156030638194;11151.498182581965;11241.269317494336;11369.431528423711;11387.829182562102;11436.428178590872;10547.947260263956;10844.024037836632;11051;11064.889351106385;11145.108391328837;10075.244356673133;10172.321566941371;10200.948995255274;10228.07947284748;10245.948770256102;7340;7435.107787065586;7541.698332066656;7545.6981720730555;7630.656620452204;7500.287472690085;7686.615669216629;7810.492317999312;7860;7860.84278314419 15:58:09 INFO - PID 3753 | |4;prototype.html;500.1147968096469;505.37773746274456;520.4249304518506;530.5702381071335;532.5207313417924;6187.164732761076;6313.273973493044;6393.808185754429;6428.807135785923;6431.228252609693;6104.1759362486055;6235.220597425322;6278.309385967551;6326.588771729835;6349.698156958379;5001.3998320201445;5007.649464537484;5013.473585273548;5020.1967685170475;5074.238864170386;4838.612910967132;5055.974720126413;5074.670146440488;5083.847484575469;5092.770825312869;4997.625178111638;5023.799048038074;5042.546170844624;5049.823256186034;5075.7715902784275;4962.734502701788;4978.352814134176;5003.849884503471;5005.27423523587;5052.242163675442;4466.3523789050405;4478.686491945565;4523.231050721385;4533.5013148553635;4565.634749220055;6181.598196117283;6227.657478838662;6228.688565571703;6229.719662615194;6311.684415779193;5765.106408506688;5769.509591684712;5771.163181338724;5810.564207684399;5821.825345239649;6583.736650533973;6618.735250590019;6661.400473957318;6675.232348279961;6692.732290708414;4849.951500484985;4853.538913803183;4869.342638743759;4891.584215341701;4916.754162291871;6815.625140617264;6893.310668933117;6921.542852287415;7054.800683883701;7081.468889833283;5033.840313678435;5054.797808087709;5070.391553013662;5071.137906555894;5093.286939828427;5027.773750181251;5061.44324124346;5113.1052065888325;5124.538791508782;5147.845564633067;5674.517665998354;5689.544836413077;5711.857203569861;5725.793555608872;5754.251947246915;6568.325077105307;6704.731810727614;6710.261871194165;6787.219469760942;6790.626515541594;1643.1291415549779;1684.2168391697674;1733.21138881811;1742.9019717577844;1767;1198.72429341252;1217.5190799634095;1228.5945637939637;1233.9758000859265;1246.7194881151668;5630.943690563124;5633.605647604629;5669.4047125051275;5783.88888055865;5849.239598852207;6613.933860661332;6622.701978410983;6727.553575981187;6801.795946121624;6811.863762724718;5758.280214973129;5770.509506691894;5776.249087618583;5816.789619944394;5822.883542329129;3557;3604.8377822998027;3605.0987253186704;3622.42041273395;3628.32875917956;6452.387023232879;6528.738850446024;6536.477081833442;6537.869242615121;6592.044153597702;6865.588064716133;6889.173299204128;6937.375636192767;6938.820400531922;7001.474889383317;6478.574713563009;6495.772647957298;6516.413522782972;6562.606243625302;6573.671316434255;6721.697523611351;6785.794616749477;6871;6926.134233220848;6931.82670433223;6636.469082473487;6732.562383445061;6742.898856517254;6752.358525940028;6808.217055038734;6331.240251169889;6351.587146835441;6391;6439.130717353098;6511.186101737283;4934.383202099738;4934.703917764946;4949.158643030765;4982.775775090057;4986.6010719143105;6743.865122697421;6781.966090169518;6783.559068660521;6808.727650894107;6844.315568443315;6570.8357291068105;6621.139251897415;6621.238557565942;6622.768203112868;6648.767293144717;6368.172137622079;6476.287608362982;6476.579022363531;6539.411452969255;6555.672216389257;6169.691515424301;6274.1843560336865;6357.55497115216;6394.232692076795;6449.3873082057125;6534.967325163534;6562.146920900253;6594.538382313192;6653.669266146693;6670.83322916931;6280.842978925563;6291.030086232008;6355.269144048493;6358.618482891041;6405.455536279468 15:58:09 INFO - PID 3753 | |5;yui.html;869.3740506835077;879.6085741844879;894.879191309173;902.62992173209;907.2515174980639;18411.53971150722;19126;19136.04319784012;19136.617267654656;19211;18408.631827363446;18692.719609205873;18754.156062977163;18774.249030038816;18818.43544693658;943.79708362702;964.2045312617084;965.840636295014;967.3615413826841;969.6896992962255;969.8496733006396;979.7550612346913;981.3523074770653;981.4307701533129;983.9065288797589;987.2940847294183;1014.5941623350645;1024.359775140537;1028.3367228137847;1032.9070383665469;1021.8518314844343;1028.9434081125535;1029.217794476196;1033.9379637221753;1035.8964103589656;742.6323969635065;748.4872862089486;748.7940816275513;748.9213632568557;750.4071783291248;18998;19770;19920.70118948217;19949.102290396822;20087.899560502105;21729.456763581038;22002.559948801096;22027.33917982463;22362.329130126123;22377.664335034988;17050.48848534546;17155.142242887807;17304.567385815328;17368;17396.21717022737;3736;3737.8504859805576;3762.021874312671;3765.548134223911;3769.736118471708;122667;135068;136889.31555342258;137056.999999999;138337;15556.377744890078;16160.83839161617;17094.145292735564;17505.56236094108;17603.471895843144;30310.54534181989;31288.21779455532;31506.212344691565;31604.841975790434;31702.36595268082;9165.450072995642;9207.26341892647;9208.861867071999;9249.445033298023;9277.536123193813;4246.44796176495;4249.660027197817;4261.829526818892;4263.829446822092;4298.247806633889;5442.755076021588;5507.531859791882;5545.500904918576;5550.694711790809;5555.472230138131;1046.1160319529986;1074.9355038697704;1094.9671509854718;1097.9560817567208;1100.6863044032411;664.4119953840885;681.9556728812711;682.9248782634006;683.3269229808634;702.125853312629;11684.88315116838;12010.519579216909;12257.455511659666;12895;13495.59513214605;667.4693618573245;672.2638710611873;674.6120980436269;690.1132045321738;691.0877641513155;5496.4503549645515;5606.374151496102;5656.2929633795775;5671.489565939003;5713.628614140068;118957.4052129751;119125;119134;119378.4031079839;119849;101137.49431252797;105759;106845;108804;108818;90515;90589.09410905938;91595;95672.52163739137;95859.04140958501;10382.688519344432;10572.312799667998;10614.787704245717;10689.198310126927;10894.945525272324;41606;42537.78731106325;42932.57067429286;43265.783671081444;43453.78273108614;54010;55611.44388556063;55855.44144558503;55884.72057639686;56664;16233.10717910479;16308.755368669243;16358.427455039016;16430;16587.66824663524;16778.748318775473;16980.23588938476;17139.400120995706;17301;17508.47474575765;15441.768373474632;15635.374585016472;15694.293756780744;15694.372225110868;15925;16701.91649041747;16762.748558771385;16806.495805125865;16928.830711692724;16951.915240423717;15994.840051599334;16029.198540073183;16240.02559846413;16307.510774676779;16551.586210344358;11917.94041029824;11971.221870578385;12080.45637946277;12348;12379.071569632133;12191.51233950632;12234.327112008925;12605.495780169058;12662.303573303558;12727.045471589483 15:58:09 INFO - PID 3753 | __end_tp_report 15:58:09 INFO - PID 3753 | __start_cc_report 15:58:09 INFO - PID 3753 | _x_x_mozilla_cycle_collect,288 15:58:09 INFO - PID 3753 | __end_cc_report 15:58:09 INFO - PID 3753 | __startTimestamp1505861889850__endTimestamp 15:58:09 INFO - PID 3753 | 15:58:09 INFO - PID 3753 | ------- Summary: start ------- 15:58:09 INFO - PID 3753 | Number of tests: 6 15:58:09 INFO - PID 3753 | 15:58:09 INFO - PID 3753 | [#0] dojo.html Cycles:165 Average:6213.47 Median:6495.68 stddev:865.91 (13.3%) stddev-sans-first:833.06 15:58:09 INFO - PID 3753 | Values: 3084.7 3110.1 3133.7 3137.5 3153.4 6192.2 6373.4 6386.5 6420.9 6429.3 6201.5 6276.7 6305.9 6324.2 6360.4 5785.3 5786.9 5788.0 5836.4 5869.1 5896.1 5925.4 5992.0 5999.8 6001.2 5824.2 5855.0 5856.5 5901.1 5932.3 973.6 5231.8 5605.7 5707.2 5742.3 5788.8 5795.5 5831.0 5834.0 5892.9 6136.3 6260.1 6281.2 6309.9 6395.5 6681.5 6752.8 6825.4 6855.4 6866.1 6780.3 6782.2 6868.4 6871.0 6883.1 6092.8 6271.9 6298.1 6309.2 6312.2 7051.6 7060.3 7070.5 7093.1 7095.5 6714.9 6766.6 6767.6 6794.1 6795.1 6808.3 6815.8 6830.8 6905.8 6909.6 6494.0 6768.2 6770.3 6808.9 6862.7 6676.8 6685.9 6692.7 6695.3 6701.9 5277.9 5282.2 5330.9 5399.2 5412.0 4688.8 4690.4 4693.9 4705.1 4748.3 5791.6 5805.7 5921.6 5926.9 5928.6 6505.3 6785.2 6787.1 6829.5 6881.1 5739.6 5787.8 5793.1 5849.2 5857.2 5836.5 5849.6 5864.9 5919.5 5925.2 6548.0 6558.4 6621.3 6621.4 6645.2 6828.8 6833.2 6837.1 6917.8 6951.5 6359.1 6495.7 6510.2 6558.1 6587.2 6627.5 6740.3 6746.3 6760.1 6767.3 6805.1 6893.3 6893.8 6909.1 6923.5 6583.1 6591.9 6605.5 6679.9 6698.5 6464.8 6561.0 6569.5 6610.5 6699.4 6494.3 6544.4 6553.7 6579.5 6635.3 6663.8 6712.4 6782.6 6789.8 6813.8 6192.5 6266.9 6411.3 6423.2 6529.7 15:58:09 INFO - PID 3753 | 15:58:09 INFO - PID 3753 | [#1] ext.html Cycles:180 Average:36144.40 Median:3445.47 stddev:50810.43 (1474.7%) stddev-sans-first:50883.53 15:58:09 INFO - PID 3753 | Values: 769.4 780.0 784.5 789.6 790.7 39860.4 40404.0 40484.6 40569.0 40666.6 37436.4 37653.8 37766.6 37792.8 38166.4 6984.7 7094.0 7097.2 7103.9 7154.1 6903.9 7089.6 7089.7 7096.2 7113.5 7068.5 7087.0 7113.4 7767.0 7949.6 7831.6 7852.1 7895.8 7910.7 7916.7 7804.3 7884.0 7906.8 7916.2 7934.1 37665.2 37982.6 38027.0 38132.2 38273.4 3390.9 3444.3 3446.6 3447.0 3448.7 31181.8 31314.4 31371.4 31486.4 31487.2 5236.9 5252.8 5270.1 5275.6 5283.8 178106.0 178869.0 178905.0 179473.0 179972.0 41074.4 41947.0 41947.0 47226.1 47869.3 49578.3 49681.5 49788.0 49863.0 49948.0 14943.4 14950.8 15351.8 15428.9 15513.9 12096.1 12179.3 12247.3 12339.5 12490.3 13954.4 14057.3 14112.2 14168.6 14229.0 5636.2 5716.6 5729.6 5823.2 5823.8 999.3 1008.2 1037.0 1038.3 1058.6 23157.9 23206.7 23233.4 23316.9 23714.4 1119.1 1122.5 1129.4 1138.5 1139.0 12495.6 12496.4 12650.4 12762.6 12810.9 217394.0 218599.0 218663.0 219366.0 219373.0 52704.0 52915.2 53017.7 53087.7 53171.7 1400.9 1460.3 1466.4 1479.3 1480.5 22471.0 22935.1 22947.1 23185.4 23211.7 37151.3 37336.8 37374.3 37390.1 37427.0 187683.0 188588.0 188611.0 190563.0 190776.0 32633.0 32818.7 32953.7 33147.8 33291.0 40762.0 41281.6 41753.4 41955.6 42078.2 31160.4 31305.7 31329.5 31381.2 31425.0 30847.7 31073.8 31100.2 31425.5 31646.7 34746.5 35419.8 35486.1 35563.1 35587.3 29608.6 29731.1 29808.4 30816.5 31380.5 22938.4 23034.1 23045.2 23347.9 23505.4 15:58:09 INFO - PID 3753 | 15:58:09 INFO - PID 3753 | [#2] jquery.html Cycles:180 Average:33294.96 Median:22098.28 stddev:74686.88 (338.0%) stddev-sans-first:74855.99 15:58:09 INFO - PID 3753 | Values: 572.2 575.8 576.2 580.0 581.4 21558.0 22461.2 22468.8 22545.3 22549.0 22770.2 23932.0 24088.6 24358.5 24397.0 6427.2 6646.2 6648.3 6648.6 6663.8 6416.1 6419.5 6486.5 6533.5 6535.2 6205.5 6413.7 6451.9 6470.0 6483.8 6452.2 6465.0 6473.6 6496.2 6503.1 6007.0 6018.4 6021.7 6023.0 6046.9 27479.3 27760.2 28548.1 28567.0 28592.7 1987.2 2061.9 2064.0 2065.2 2065.5 18213.8 18216.1 18234.5 18318.5 18339.6 2326.4 2340.5 2393.1 2411.9 2416.5 118885.0 122637.4 122764.4 129461.4 138083.0 18636.4 20207.9 20283.6 20690.4 21484.9 40722.2 40861.0 40900.8 40987.6 41062.4 10936.4 10979.8 11093.6 11146.3 11263.2 9205.6 9239.4 9329.6 9401.7 9430.9 10741.7 10757.4 10889.4 11264.3 11373.8 3471.7 3810.5 3814.9 3841.8 3972.4 935.3 954.8 968.7 972.0 981.6 18740.5 18829.0 19190.9 19298.1 19330.2 993.5 1014.1 1022.5 1030.3 1036.5 10430.7 10564.7 10587.4 10729.9 10835.6 424175.3 441955.0 443826.0 450196.0 454497.0 107802.5 108996.0 111736.4 112457.0 113432.4 1156.3 1178.6 1230.6 1237.3 1239.6 19640.1 19879.4 19926.8 20268.7 20439.5 53630.7 54763.7 55913.4 56299.7 56486.7 63561.7 63600.0 63883.4 63973.4 64682.0 13917.0 13955.4 14124.1 14197.5 14227.2 22002.2 22194.3 22203.7 22324.1 22558.7 17226.0 17419.6 17639.9 17711.2 17916.2 15786.9 15876.8 16240.0 16254.9 16409.9 18283.5 18302.5 18358.2 18381.4 18393.8 14012.8 14037.8 14055.6 14061.3 14068.2 17544.6 17613.6 17690.3 17741.6 17844.2 15:58:09 INFO - PID 3753 | 15:58:09 INFO - PID 3753 | [#3] mootools.html Cycles:180 Average:9479.92 Median:2658.72 stddev:6700.45 (252.0%) stddev-sans-first:6690.64 15:58:09 INFO - PID 3753 | Values: 1239.4 1298.6 1306.9 1312.8 1319.5 15200.6 15678.1 15784.0 15939.1 16005.8 15592.5 15614.8 15639.3 15719.3 15771.5 2692.9 2699.5 2784.6 2797.5 2800.6 2663.5 2690.3 2692.6 2695.6 2697.4 2269.4 2655.9 2660.9 2686.4 2688.3 2568.5 2728.7 2743.0 2765.6 2768.8 2644.4 2656.5 2670.3 2672.4 2682.2 14546.1 14937.4 14943.3 15020.8 15036.1 8014.0 8330.3 8361.3 8411.3 8412.9 5402.4 5645.4 5696.7 5753.3 5796.8 1964.1 1965.4 1971.1 2013.7 2024.8 24357.9 26925.2 26936.0 27035.7 27302.3 6235.8 7416.6 7445.1 7698.8 7706.4 20290.9 20422.2 20451.8 20492.7 20494.0 8076.9 8296.1 8319.6 8330.0 8368.2 7310.0 7341.7 7401.4 7531.6 7545.1 5511.9 5606.1 5625.4 5642.4 5698.1 1792.0 1829.5 1840.8 1863.8 1885.4 876.2 908.1 910.7 914.8 918.5 7534.5 7725.7 7726.4 7737.4 7743.7 689.1 695.3 698.2 716.3 721.4 5189.8 5206.5 5226.9 5235.5 5250.3 20083.3 20543.7 21096.8 21450.2 21710.0 20690.2 21227.3 21290.7 21357.3 21572.5 10850.1 11379.1 11423.2 11423.7 11575.8 7455.5 9047.5 9061.0 9264.2 9283.3 17061.2 17221.9 17597.6 17610.1 17828.2 17784.6 18105.0 18965.2 18988.8 19076.2 12233.3 12338.6 12361.8 12427.1 12447.3 13765.2 13938.8 13961.5 14050.2 14066.2 11151.5 11241.3 11369.4 11387.8 11436.4 10547.9 10844.0 11051.0 11064.9 11145.1 10075.2 10172.3 10200.9 10228.1 10245.9 7340.0 7435.1 7541.7 7545.7 7630.7 7500.3 7686.6 7810.5 7860.0 7860.8 15:58:09 INFO - PID 3753 | 15:58:09 INFO - PID 3753 | [#4] prototype.html Cycles:180 Average:5546.22 Median:6229.20 stddev:1561.15 (25.1%) stddev-sans-first:1518.89 15:58:09 INFO - PID 3753 | Values: 500.1 505.4 520.4 530.6 532.5 6187.2 6313.3 6393.8 6428.8 6431.2 6104.2 6235.2 6278.3 6326.6 6349.7 5001.4 5007.6 5013.5 5020.2 5074.2 4838.6 5056.0 5074.7 5083.8 5092.8 4997.6 5023.8 5042.5 5049.8 5075.8 4962.7 4978.4 5003.8 5005.3 5052.2 4466.4 4478.7 4523.2 4533.5 4565.6 6181.6 6227.7 6228.7 6229.7 6311.7 5765.1 5769.5 5771.2 5810.6 5821.8 6583.7 6618.7 6661.4 6675.2 6692.7 4850.0 4853.5 4869.3 4891.6 4916.8 6815.6 6893.3 6921.5 7054.8 7081.5 5033.8 5054.8 5070.4 5071.1 5093.3 5027.8 5061.4 5113.1 5124.5 5147.8 5674.5 5689.5 5711.9 5725.8 5754.3 6568.3 6704.7 6710.3 6787.2 6790.6 1643.1 1684.2 1733.2 1742.9 1767.0 1198.7 1217.5 1228.6 1234.0 1246.7 5630.9 5633.6 5669.4 5783.9 5849.2 6613.9 6622.7 6727.6 6801.8 6811.9 5758.3 5770.5 5776.2 5816.8 5822.9 3557.0 3604.8 3605.1 3622.4 3628.3 6452.4 6528.7 6536.5 6537.9 6592.0 6865.6 6889.2 6937.4 6938.8 7001.5 6478.6 6495.8 6516.4 6562.6 6573.7 6721.7 6785.8 6871.0 6926.1 6931.8 6636.5 6732.6 6742.9 6752.4 6808.2 6331.2 6351.6 6391.0 6439.1 6511.2 4934.4 4934.7 4949.2 4982.8 4986.6 6743.9 6782.0 6783.6 6808.7 6844.3 6570.8 6621.1 6621.2 6622.8 6648.8 6368.2 6476.3 6476.6 6539.4 6555.7 6169.7 6274.2 6357.6 6394.2 6449.4 6535.0 6562.1 6594.5 6653.7 6670.8 6280.8 6291.0 6355.3 6358.6 6405.5 15:58:09 INFO - PID 3753 | 15:58:09 INFO - PID 3753 | [#5] yui.html Cycles:180 Average:23946.63 Median:19062.00 stddev:34124.17 (179.0%) stddev-sans-first:34175.90 15:58:09 INFO - PID 3753 | Values: 869.4 879.6 894.9 902.6 907.3 18411.5 19126.0 19136.0 19136.6 19211.0 18408.6 18692.7 18754.2 18774.2 18818.4 943.8 964.2 965.8 967.4 969.7 969.8 979.8 981.4 981.4 983.9 987.3 1014.6 1024.4 1028.3 1032.9 1021.9 1028.9 1029.2 1033.9 1035.9 742.6 748.5 748.8 748.9 750.4 18998.0 19770.0 19920.7 19949.1 20087.9 21729.5 22002.6 22027.3 22362.3 22377.7 17050.5 17155.1 17304.6 17368.0 17396.2 3736.0 3737.9 3762.0 3765.5 3769.7 122667.0 135068.0 136889.3 137057.0 138337.0 15556.4 16160.8 17094.1 17505.6 17603.5 30310.5 31288.2 31506.2 31604.8 31702.4 9165.5 9207.3 9208.9 9249.4 9277.5 4246.4 4249.7 4261.8 4263.8 4298.2 5442.8 5507.5 5545.5 5550.7 5555.5 1046.1 1074.9 1095.0 1098.0 1100.7 664.4 682.0 682.9 683.3 702.1 11684.9 12010.5 12257.5 12895.0 13495.6 667.5 672.3 674.6 690.1 691.1 5496.5 5606.4 5656.3 5671.5 5713.6 118957.4 119125.0 119134.0 119378.4 119849.0 101137.5 105759.0 106845.0 108804.0 108818.0 90515.0 90589.1 91595.0 95672.5 95859.0 10382.7 10572.3 10614.8 10689.2 10894.9 41606.0 42537.8 42932.6 43265.8 43453.8 54010.0 55611.4 55855.4 55884.7 56664.0 16233.1 16308.8 16358.4 16430.0 16587.7 16778.7 16980.2 17139.4 17301.0 17508.5 15441.8 15635.4 15694.3 15694.4 15925.0 16701.9 16762.7 16806.5 16928.8 16951.9 15994.8 16029.2 16240.0 16307.5 16551.6 11917.9 11971.2 12080.5 12348.0 12379.1 12191.5 12234.3 12605.5 12662.3 12727.0 15:58:09 INFO - PID 3753 | -------- Summary: end -------- 15:58:09 INFO - PID 3753 | 15:58:10 INFO - TEST-INFO | 3753: exit 0 15:58:10 INFO - TEST-OK | dromaeo_css | took 1116370ms 15:58:10 INFO - TEST-START | kraken 15:58:10 INFO - Initialising browser for kraken test... 15:58:10 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33924/getInfo.html -profile /tmp/tmpcs06LV/profile 15:58:10 INFO - TEST-INFO | started process 4139 (/builds/slave/test/build/application/firefox/firefox http://localhost:33924/getInfo.html) 15:58:17 INFO - TEST-INFO | 4139: exit 0 15:58:17 INFO - Browser initialized. 15:58:17 INFO - Running cycle 1/1 for kraken test... 15:58:17 INFO - Using env: {'DISPLAY': ':0', 15:58:17 INFO - 'HOME': '/home/cltbld', 15:58:17 INFO - 'JSGC_DISABLE_POISONING': '1', 15:58:17 INFO - 'LANG': 'en_US.UTF-8', 15:58:17 INFO - 'LANGUAGE': 'en_US:en', 15:58:17 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:58:17 INFO - 'LOGNAME': 'cltbld', 15:58:17 INFO - 'MAIL': '/var/mail/cltbld', 15:58:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:58:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:58:17 INFO - 'MOZ_ACCELERATED': '1', 15:58:17 INFO - 'MOZ_CRASHREPORTER': '1', 15:58:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:17 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:58:17 INFO - 'MOZ_NO_REMOTE': '1', 15:58:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:58:17 INFO - 'MOZ_WEBRENDER': '1', 15:58:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:58:17 INFO - 'NO_EM_RESTART': '1', 15:58:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:58:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:17 INFO - 'PWD': '/builds/slave/test', 15:58:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:58:17 INFO - 'RUST_BACKTRACE': 'full', 15:58:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:58:17 INFO - 'SHELL': '/bin/bash', 15:58:17 INFO - 'SHLVL': '1', 15:58:17 INFO - 'TERM': 'linux', 15:58:17 INFO - 'TMOUT': '86400', 15:58:17 INFO - 'USER': 'cltbld', 15:58:17 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998', 15:58:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:17 INFO - '_': '/tools/buildbot/bin/python'} 15:58:17 INFO - TEST-INFO | started process 4383 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcs06LV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:58:17 INFO - PID 4383 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:58:18 INFO - PID 4383 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 15:58:18 INFO - PID 4383 | 15:58:18 INFO - PID 4383 | (/builds/slave/test/build/application/firefox/firefox:4442): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:58:18 INFO - PID 4383 | 15:58:28 INFO - PID 4383 | RSS: Main: 216567808 15:58:28 INFO - PID 4383 | 15:58:31 INFO - PID 4383 | Cycle 1(1): loaded http://localhost:33924/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33924/tests/kraken/driver.html?testName=audio-beat-detection) 15:58:32 INFO - PID 4383 | RSS: Main: 215281664 15:58:32 INFO - PID 4383 | 15:58:35 INFO - PID 4383 | Cycle 1(1): loaded http://localhost:33924/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33924/tests/kraken/driver.html?testName=audio-dft) 15:58:35 INFO - PID 4383 | RSS: Main: 216178688 15:58:35 INFO - PID 4383 | 15:58:38 INFO - PID 4383 | Cycle 1(1): loaded http://localhost:33924/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33924/tests/kraken/driver.html?testName=audio-fft) 15:58:39 INFO - PID 4383 | RSS: Main: 211902464 15:58:39 INFO - PID 4383 | 15:58:41 INFO - PID 4383 | Cycle 1(1): loaded http://localhost:33924/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33924/tests/kraken/driver.html?testName=audio-oscillator) 15:58:42 INFO - PID 4383 | RSS: Main: 212201472 15:58:42 INFO - PID 4383 | 15:58:44 INFO - PID 4383 | Cycle 1(1): loaded http://localhost:33924/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33924/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:58:45 INFO - PID 4383 | RSS: Main: 211054592 15:58:45 INFO - PID 4383 | 15:58:48 INFO - PID 4383 | Cycle 1(1): loaded http://localhost:33924/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33924/tests/kraken/driver.html?testName=imaging-darkroom) 15:58:48 INFO - PID 4383 | RSS: Main: 215252992 15:58:49 INFO - PID 4383 | 15:58:52 INFO - PID 4383 | Cycle 1(1): loaded http://localhost:33924/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33924/tests/kraken/driver.html?testName=imaging-desaturate) 15:58:53 INFO - PID 4383 | RSS: Main: 214872064 15:58:53 INFO - PID 4383 | 15:58:56 INFO - PID 4383 | Cycle 1(1): loaded http://localhost:33924/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33924/tests/kraken/driver.html?testName=json-parse-financial) 15:58:57 INFO - PID 4383 | RSS: Main: 215773184 15:58:57 INFO - PID 4383 | 15:58:58 INFO - PID 4383 | Cycle 1(1): loaded http://localhost:33924/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33924/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:58:59 INFO - PID 4383 | RSS: Main: 215932928 15:58:59 INFO - PID 4383 | 15:59:00 INFO - PID 4383 | Cycle 1(1): loaded http://localhost:33924/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33924/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:59:01 INFO - PID 4383 | RSS: Main: 213131264 15:59:01 INFO - PID 4383 | 15:59:03 INFO - PID 4383 | Cycle 1(1): loaded http://localhost:33924/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33924/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:59:04 INFO - PID 4383 | RSS: Main: 211714048 15:59:04 INFO - PID 4383 | 15:59:07 INFO - PID 4383 | Cycle 1(1): loaded http://localhost:33924/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33924/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:59:07 INFO - PID 4383 | RSS: Main: 212504576 15:59:07 INFO - PID 4383 | 15:59:10 INFO - PID 4383 | Cycle 1(1): loaded http://localhost:33924/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33924/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:59:11 INFO - PID 4383 | RSS: Main: 212705280 15:59:11 INFO - PID 4383 | 15:59:13 INFO - PID 4383 | Cycle 1(1): loaded http://localhost:33924/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33924/tests/kraken/driver.html?testName=ai-astar) 15:59:13 INFO - PID 4383 | RSS: Main: 214654976 15:59:13 INFO - PID 4383 | 15:59:13 INFO - PID 4383 | __start_tp_report 15:59:13 INFO - PID 4383 | _x_x_mozilla_page_load 15:59:13 INFO - PID 4383 | _x_x_mozilla_page_load_details 15:59:13 INFO - PID 4383 | |i|pagename|runs| 15:59:13 INFO - PID 4383 | |0;ai-astar;113;107;116;107;112;118;110;112;113;116 15:59:13 INFO - PID 4383 | |1;audio-beat-detection;124;122;122;122;165;120;122;120;122;124 15:59:13 INFO - PID 4383 | |2;audio-dft;170;171;174;164;174;171;163;166;163;165 15:59:13 INFO - PID 4383 | |3;audio-fft;82;77;75;74;80;78;78;74;75;76 15:59:13 INFO - PID 4383 | |4;audio-oscillator;80;83;79;82;81;91;80;79;78;88 15:59:13 INFO - PID 4383 | |5;imaging-gaussian-blur;112;116;122;115;114;123;114;121;114;122 15:59:13 INFO - PID 4383 | |6;imaging-darkroom;155;151;151;148;149;153;152;156;149;155 15:59:13 INFO - PID 4383 | |7;imaging-desaturate;106;105;100;96;103;96;94;94;96;98 15:59:13 INFO - PID 4383 | |8;json-parse-financial;53;56;53;53;55;55;54;54;56;56 15:59:13 INFO - PID 4383 | |9;json-stringify-tinderbox;46;38;43;39;46;39;45;47;38;38 15:59:13 INFO - PID 4383 | |10;stanford-crypto-aes;83;78;80;75;88;77;84;87;79;77 15:59:13 INFO - PID 4383 | |11;stanford-crypto-ccm;113;109;112;119;117;125;112;118;115;116 15:59:13 INFO - PID 4383 | |12;stanford-crypto-pbkdf2;180;181;170;167;177;170;179;177;170;169 15:59:13 INFO - PID 4383 | |13;stanford-crypto-sha256-iterative;67;69;78;66;71;66;72;64;64;69 15:59:13 INFO - PID 4383 | __end_tp_report 15:59:13 INFO - PID 4383 | __start_cc_report 15:59:13 INFO - PID 4383 | _x_x_mozilla_cycle_collect,763 15:59:13 INFO - PID 4383 | __end_cc_report 15:59:13 INFO - PID 4383 | __startTimestamp1505861953438__endTimestamp 15:59:13 INFO - PID 4383 | 15:59:13 INFO - PID 4383 | ------- Summary: start ------- 15:59:13 INFO - PID 4383 | Number of tests: 14 15:59:13 INFO - PID 4383 | 15:59:13 INFO - PID 4383 | [#0] ai-astar Cycles:10 Average:112.40 Median:113.00 stddev:3.69 (3.3%) stddev-sans-first:3.91 15:59:13 INFO - PID 4383 | Values: 113.0 107.0 116.0 107.0 112.0 118.0 110.0 112.0 113.0 116.0 15:59:13 INFO - PID 4383 | 15:59:13 INFO - PID 4383 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:122.00 stddev:13.66 (11.2%) stddev-sans-first:14.47 15:59:13 INFO - PID 4383 | Values: 124.0 122.0 122.0 122.0 165.0 120.0 122.0 120.0 122.0 124.0 15:59:13 INFO - PID 4383 | 15:59:13 INFO - PID 4383 | [#2] audio-dft Cycles:10 Average:168.10 Median:170.50 stddev:4.38 (2.6%) stddev-sans-first:4.59 15:59:13 INFO - PID 4383 | Values: 170.0 171.0 174.0 164.0 174.0 171.0 163.0 166.0 163.0 165.0 15:59:13 INFO - PID 4383 | 15:59:13 INFO - PID 4383 | [#3] audio-fft Cycles:10 Average:76.90 Median:77.50 stddev:2.64 (3.4%) stddev-sans-first:2.06 15:59:13 INFO - PID 4383 | Values: 82.0 77.0 75.0 74.0 80.0 78.0 78.0 74.0 75.0 76.0 15:59:13 INFO - PID 4383 | 15:59:13 INFO - PID 4383 | [#4] audio-oscillator Cycles:10 Average:82.10 Median:81.50 stddev:4.23 (5.2%) stddev-sans-first:4.42 15:59:13 INFO - PID 4383 | Values: 80.0 83.0 79.0 82.0 81.0 91.0 80.0 79.0 78.0 88.0 15:59:13 INFO - PID 4383 | 15:59:13 INFO - PID 4383 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:118.50 stddev:4.19 (3.5%) stddev-sans-first:3.98 15:59:13 INFO - PID 4383 | Values: 112.0 116.0 122.0 115.0 114.0 123.0 114.0 121.0 114.0 122.0 15:59:13 INFO - PID 4383 | 15:59:13 INFO - PID 4383 | [#6] imaging-darkroom Cycles:10 Average:151.90 Median:152.50 stddev:2.81 (1.8%) stddev-sans-first:2.74 15:59:13 INFO - PID 4383 | Values: 155.0 151.0 151.0 148.0 149.0 153.0 152.0 156.0 149.0 155.0 15:59:13 INFO - PID 4383 | 15:59:13 INFO - PID 4383 | [#7] imaging-desaturate Cycles:10 Average:98.80 Median:95.00 stddev:4.47 (4.7%) stddev-sans-first:3.91 15:59:13 INFO - PID 4383 | Values: 106.0 105.0 100.0 96.0 103.0 96.0 94.0 94.0 96.0 98.0 15:59:13 INFO - PID 4383 | 15:59:13 INFO - PID 4383 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.27 (2.3%) stddev-sans-first:1.22 15:59:13 INFO - PID 4383 | Values: 53.0 56.0 53.0 53.0 55.0 55.0 54.0 54.0 56.0 56.0 15:59:13 INFO - PID 4383 | 15:59:13 INFO - PID 4383 | [#9] json-stringify-tinderbox Cycles:10 Average:41.90 Median:44.00 stddev:3.84 (8.7%) stddev-sans-first:3.78 15:59:13 INFO - PID 4383 | Values: 46.0 38.0 43.0 39.0 46.0 39.0 45.0 47.0 38.0 38.0 15:59:13 INFO - PID 4383 | 15:59:13 INFO - PID 4383 | [#10] stanford-crypto-aes Cycles:10 Average:80.80 Median:81.50 stddev:4.47 (5.5%) stddev-sans-first:4.67 15:59:13 INFO - PID 4383 | Values: 83.0 78.0 80.0 75.0 88.0 77.0 84.0 87.0 79.0 77.0 15:59:13 INFO - PID 4383 | 15:59:13 INFO - PID 4383 | [#11] stanford-crypto-ccm Cycles:10 Average:115.60 Median:116.50 stddev:4.53 (3.9%) stddev-sans-first:4.70 15:59:13 INFO - PID 4383 | Values: 113.0 109.0 112.0 119.0 117.0 125.0 112.0 118.0 115.0 116.0 15:59:13 INFO - PID 4383 | 15:59:13 INFO - PID 4383 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.00 Median:177.00 stddev:5.27 (3.0%) stddev-sans-first:5.12 15:59:13 INFO - PID 4383 | Values: 180.0 181.0 170.0 167.0 177.0 170.0 179.0 177.0 170.0 169.0 15:59:13 INFO - PID 4383 | 15:59:13 INFO - PID 4383 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.00 stddev:4.27 (6.2%) stddev-sans-first:4.49 15:59:13 INFO - PID 4383 | Values: 67.0 69.0 78.0 66.0 71.0 66.0 72.0 64.0 64.0 69.0 15:59:13 INFO - PID 4383 | -------- Summary: end -------- 15:59:13 INFO - PID 4383 | 15:59:14 INFO - PID 4383 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:59:14 INFO - TEST-INFO | 4383: exit 0 15:59:14 INFO - TEST-OK | kraken | took 63848ms 15:59:14 INFO - SUITE-END | took 1180s 15:59:15 INFO - Completed test suite (00:19:40) 15:59:15 INFO - javascript benchmark 15:59:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8533.008193220023, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3084.70695283948, 3110.0669799060274, 3133.7179653831163, 3137.513685378767, 3153.4008538377707, 6192.225971753531, 6373.426391624753, 6386.521010924182, 6420.935790642092, 6429.292777794439, 6201.53488488364, 6276.748930042792, 6305.936940630592, 6324.209473815773, 6360.395762402564, 5785.27684039495, 5786.913196302048, 5787.971060144693, 5836.416358364172, 5869.119632055204, 5896.056630939051, 5925.4370834770625, 5992.0, 5999.790007349744, 6001.2498437695285, 5824.184614154022, 5854.973173344144, 5856.502197313233, 5901.05583106703, 5932.2584676915385, 973.5754072315902, 5231.843044708627, 5605.691686957216, 5707.200991861143, 5742.28221472316, 5788.826335209908, 5795.507381872546, 5831.037878750001, 5834.037383831663, 5892.882142357129, 6136.294326152505, 6260.09228661846, 6281.24625044993, 6309.9220732601225, 6395.456386207178, 6681.53229273951, 6752.797416077526, 6825.419839313615, 6855.4172895303955, 6866.107406037183, 6780.254172041072, 6782.152230971129, 6868.381845633867, 6871.0, 6883.070785443951, 6092.847678808021, 6271.905921411182, 6298.086777417295, 6309.242890853127, 6312.242530896322, 7051.612161331073, 7060.293970602898, 7070.469714771412, 7093.077899872984, 7095.503314767917, 6714.932850671528, 6766.594004359656, 6767.560108592926, 6794.11676482064, 6795.082663840417, 6808.285130061371, 6815.795526134224, 6830.829229269308, 6905.792826215222, 6909.550879192937, 6494.0, 6768.187817461838, 6770.289119642367, 6808.8978665320055, 6862.656867156622, 6676.83307917306, 6685.866282674319, 6692.665366731644, 6695.296993815579, 6701.865962680719, 5277.868053298698, 5282.181261904334, 5330.8933821324135, 5399.163129714901, 5412.0, 4688.765561721901, 4690.413698287714, 4693.859184224479, 4705.106029854385, 4748.335233067373, 5791.5656325775735, 5805.709714514257, 5921.644701317935, 5926.911096333559, 5928.584999050111, 6505.349465053551, 6785.185777706608, 6787.083743694538, 6829.4877884157895, 6881.071055407456, 5739.569532285095, 5787.797427090116, 5793.131030345482, 5849.210356601804, 5857.238558987305, 5836.533077353802, 5849.590528662953, 5864.882702345929, 5919.4968427684125, 5925.1704761332585, 6547.985062315349, 6558.376954189344, 6621.271660117383, 6621.437177839841, 6645.169353830771, 6828.760993365208, 6833.2483426824, 6837.076994605664, 6917.757878474229, 6951.513394062468, 6359.109724638462, 6495.675216239264, 6510.18622672166, 6558.114654521579, 6587.176602924635, 6627.502937279627, 6740.258571557223, 6746.291639377795, 6760.08738820253, 6767.25560188369, 6805.081314022544, 6893.310668933066, 6893.7931862044215, 6909.1018167637885, 6923.480738944499, 6583.111279977142, 6591.934080659324, 6605.504587155887, 6679.899801503078, 6698.531102822951, 6464.806055818333, 6561.0, 6569.540132190701, 6610.4711623071, 6699.43054840344, 6494.253160886369, 6544.3782840630065, 6553.672316384257, 6579.539432239697, 6635.303293154149, 6663.800085997428, 6712.429443497357, 6782.626955517297, 6789.762358317435, 6813.7955861324235, 6192.473639740672, 6266.937330626818, 6411.294757576569, 6423.164988551458, 6529.7388104475285], "value": 6148.056642607453, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [769.4037121231049, 780.0210735526915, 784.5292824305418, 789.6130895861027, 790.7113903425247, 39860.40209396858, 40404.0, 40484.59515404849, 40569.0, 40666.59333406665, 37436.43845342322, 37653.81173094131, 37766.62233377666, 37792.81103594478, 38166.4275035874, 6984.720611175547, 7094.0, 7097.18382386027, 7103.928960710378, 7154.105736782903, 6903.930960690379, 7089.610071446068, 7089.71641134354, 7096.183938847047, 7113.502054856162, 7068.540544864593, 7086.964565177167, 7113.395361394262, 7767.0, 7949.562774047454, 7831.608419578998, 7852.097008843999, 7895.8026049348655, 7910.7231246906085, 7916.6833326666865, 7804.2585954334245, 7884.0, 7906.7627971160955, 7916.168802275735, 7934.087579928324, 37665.246695065944, 37982.62017379846, 38027.0, 38132.23735525302, 38273.425898611546, 3390.9491357629654, 3444.3111377724304, 3446.638102999199, 3446.965910226922, 3448.689617934398, 31181.8440907794, 31314.373712525623, 31371.37257254865, 31486.37027259442, 31487.212819679462, 5236.92144617831, 5252.816151434681, 5270.077736396153, 5275.57795376369, 5283.762230699628, 178106.0, 178869.0, 178905.0, 179473.0, 179972.0, 41074.38388424176, 41946.95132621709, 41947.0, 47226.05547889023, 47869.28196077062, 49578.25632615513, 49681.50318496769, 49788.0, 49863.0, 49948.0, 14943.40226390954, 14950.775738363933, 15351.846481535265, 15428.922855385876, 15513.922430388002, 12096.092793040558, 12179.330136842382, 12247.265164090182, 12339.50641974311, 12490.313032783284, 13954.372053257628, 14057.297135143202, 14112.22382768937, 14168.645783855485, 14229.0, 5636.210930469738, 5716.599838011382, 5729.627574207663, 5823.213866128019, 5823.826014821932, 999.2555546118189, 1008.1732978957185, 1036.9637062702768, 1038.2680210451613, 1058.629479682105, 23157.88421057884, 23206.65190022151, 23233.419164521023, 23316.883415583154, 23714.407139821644, 1119.0879433261866, 1122.5453691255054, 1129.4409267412684, 1138.5445821671233, 1138.9886101138882, 12495.625131246077, 12496.437660305308, 12650.430730617145, 12762.553310634268, 12810.9359453204, 217394.0, 218599.0, 218663.0, 219366.0, 219373.0, 52704.0, 52915.206271905954, 53017.73491132597, 53087.73456132694, 53171.73414132905, 1400.9229492378017, 1460.3355473259642, 1466.3841186701611, 1479.3047267784125, 1480.5040311495554, 22471.0, 22935.082596695946, 22947.082116715144, 23185.420364491023, 23211.651825222296, 37151.256974860895, 37336.813315934334, 37374.252514950094, 37390.06524836901, 37427.0, 187683.0, 188588.0, 188611.0, 190563.0, 190776.0, 32633.0, 32818.67181328156, 32953.67046329506, 33147.83426082854, 33291.00126996194, 40762.0, 41281.58718412898, 41753.373699393924, 41955.58044419517, 42078.15843683048, 31160.37679246357, 31305.686943130277, 31329.53005704962, 31381.215469613442, 31425.0, 30847.691523084482, 31073.8446307767, 31100.22249443782, 31425.52861707122, 31646.683533164374, 34746.47880281747, 35419.82290088533, 35486.11284717903, 35563.11092222715, 35587.28825423529, 29608.555871661512, 29731.10806675803, 29808.403831923675, 30816.53775193419, 31380.529292061095, 22938.42653933665, 23034.078636854338, 23045.193418230283, 23347.88326058359, 23505.412364690335], "value": 16726.149360531377, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.2360648534207, 575.8071046199523, 576.2019602850054, 579.9618682558219, 581.4185814185814, 21558.0, 22461.21385751497, 22468.775312246893, 22545.32364029082, 22549.0, 22770.203042893503, 23932.0, 24088.638670419958, 24358.512829743395, 24396.999999999956, 6427.196600424947, 6646.202455705298, 6648.268690444047, 6648.601083934979, 6663.800085997404, 6416.069669897862, 6419.518536109787, 6486.545941784077, 6533.509986751013, 6535.215774107114, 6205.534584906128, 6413.7113829877535, 6451.903221451682, 6470.029495575654, 6483.773067942623, 6452.161219041518, 6465.0, 6473.643949582794, 6496.187976502937, 6503.089567460559, 6006.96996515019, 6018.3981601839905, 6021.729022194012, 6023.036314189752, 6046.939530604682, 27479.31301717473, 27760.167194984184, 28548.14355569336, 28567.000154994475, 28592.714072859213, 1987.1653905359783, 2061.865978711394, 2063.988645563678, 2065.215218217083, 2065.483629092727, 18213.817861821346, 18216.08919554017, 18234.452966410896, 18318.450446486626, 18339.633207335777, 2326.4183954011496, 2340.5318936212825, 2393.085531574733, 2411.9276421707377, 2416.4683769570665, 118885.0, 122637.38681306626, 122764.38617806943, 129461.35269323687, 138082.99999999898, 18636.44090677282, 20207.898960505103, 20283.594328113355, 20690.379288621367, 21484.892575537335, 40722.1855562893, 40861.0, 40900.795496022925, 40987.59012409898, 41062.38406423906, 10936.398498082523, 10979.83530247047, 11093.611723589796, 11146.275492092987, 11263.155263355282, 9205.585748641328, 9239.445633262027, 9329.580168892417, 9401.717948461557, 9430.858537121949, 10741.677749667522, 10757.408342541079, 10889.401082940356, 11264.324140551593, 11373.829392559119, 3471.7049050830456, 3810.542734871833, 3814.8664796731982, 3841.750286231386, 3972.4041393790585, 935.3499317973943, 954.7804005078732, 968.7093871838421, 971.9697121051707, 981.622075500923, 18740.53148671267, 18829.0, 19190.904045479685, 19298.13158407875, 19330.226790928486, 993.5231089077283, 1014.0569270578433, 1022.5296363672645, 1030.253066526777, 1036.512838965685, 10430.739231519272, 10564.735881603021, 10587.417692026858, 10729.9463502682, 10835.5913731216, 424175.3303172792, 441955.0, 443826.0, 450196.0, 454497.0, 107802.46098769456, 108996.0, 111736.4413177929, 112457.0, 113432.43283783528, 1156.259993604094, 1178.5816035307514, 1230.6492649595002, 1237.3194742891553, 1239.6219153158193, 19640.116194771555, 19879.403617891487, 19926.800731992495, 20268.695969560762, 20439.4890127748, 53630.73184634052, 54763.72618136884, 55913.44086559083, 56299.71850140723, 56486.71756641355, 63561.68219158875, 63600.0, 63883.36116638774, 63973.36026639674, 64682.0, 13917.0, 13955.441782328593, 14124.081934674214, 14197.503087391891, 14227.217503037433, 22002.229921952654, 22194.334169974925, 22203.666944995515, 22324.10703571839, 22558.661620075385, 17226.0, 17419.564510886823, 17639.911800440917, 17711.20299586496, 17916.193771280585, 15786.9210653946, 15876.841231587536, 16240.0, 16254.862159649185, 16409.91795041017, 18283.451496455127, 18302.542436439195, 18358.1738821756, 18381.448556543324, 18393.81606183921, 14012.789808152682, 14037.789433158716, 14055.648608784453, 14061.296935153407, 14068.155910645393, 17544.561385964942, 17613.559661008578, 17690.292388304322, 17741.64516709633, 17844.19701113427], "value": 12195.579953832312, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1239.41127964217, 1298.5844529750477, 1306.9215847049172, 1312.7768279392503, 1319.5051855554168, 15200.619984500394, 15678.137702426362, 15784.0, 15939.123348215844, 16005.75991360129, 15592.532224033268, 15614.76577851333, 15639.296231669574, 15719.292631831566, 15771.526854194364, 2692.9461410771773, 2699.500592390404, 2784.554471284595, 2797.482465743844, 2800.565912283599, 2663.547196976509, 2690.3408664877134, 2692.6364940732947, 2695.649565556475, 2697.366118962052, 2269.375921621551, 2655.8539280339573, 2660.946781064378, 2686.449277898036, 2688.3413563676927, 2568.511982723286, 2728.6998430172566, 2742.9588556171675, 2765.5989881467076, 2768.8477133757633, 2644.352133727249, 2656.495265899473, 2670.319068637485, 2672.438787854553, 2682.2355628645937, 14546.127232365987, 14937.402503899832, 14943.252837358088, 15020.774688379683, 15036.097834129987, 8014.0, 8330.333573314121, 8361.331093512505, 8411.285040771541, 8412.915870841274, 5402.35171779389, 5645.350784659776, 5696.6866822324755, 5753.33836608787, 5796.82609521715, 1964.0948280168357, 1965.4300252926635, 1971.112999150388, 2013.6778115501595, 2024.8278896293832, 24357.878210608833, 26925.192244232705, 26936.0, 27035.729642703518, 27302.317442063908, 6235.750569977241, 7416.555006699616, 7445.106587209461, 7698.846023079507, 7706.38348932084, 20290.898545507473, 20422.183112675328, 20451.79548204499, 20492.69260961087, 20494.0, 8076.919230807616, 8296.128906464855, 8319.584020798935, 8330.042045164762, 8368.24685778283, 7310.0, 7341.70633174667, 7401.444891633149, 7531.623418829036, 7545.056867891513, 5511.862203444946, 5606.131049687305, 5625.38120806711, 5642.379338272786, 5698.116791897262, 1791.9552011199823, 1829.5151784776779, 1840.8251216134445, 1863.8229368209998, 1885.415521188436, 876.202655583416, 908.1236606674458, 910.7131253655077, 914.7941713114496, 918.4672889723944, 7534.472586918863, 7725.690972361042, 7726.38188944883, 7737.381009519225, 7743.728969486041, 689.1316940654812, 695.3498478922224, 698.249381914444, 716.30160593421, 721.4120491799167, 5189.766460509286, 5206.505381988705, 5226.895462090737, 5235.502627250406, 5250.317458730417, 20083.29708460226, 20543.691844622343, 21096.78903210979, 21450.249241276793, 21710.0, 20690.17239310411, 21227.257046003626, 21290.680639790415, 21357.25249616287, 21572.4606884826, 10850.131989440826, 11379.146564007733, 11423.200375973769, 11423.714407139889, 11575.826362604568, 7455.515391499535, 9047.457152570867, 9061.0, 9264.21254193374, 9283.303752218746, 17061.232244548777, 17221.91389043097, 17597.560060998578, 17610.11949402499, 17828.19773110239, 17784.644307113525, 18105.0, 18965.241390344232, 18988.816286127272, 19076.236950521823, 12233.266004039786, 12338.629841104781, 12361.752764944471, 12427.130100892851, 12447.315397653216, 13765.242911639956, 13938.790918136034, 13961.511347102803, 14050.227237502035, 14066.156030638194, 11151.498182581965, 11241.269317494336, 11369.431528423711, 11387.829182562102, 11436.428178590872, 10547.947260263956, 10844.024037836632, 11051.0, 11064.889351106385, 11145.108391328837, 10075.244356673133, 10172.321566941371, 10200.948995255274, 10228.07947284748, 10245.948770256102, 7340.0, 7435.107787065586, 7541.698332066656, 7545.6981720730555, 7630.656620452204, 7500.287472690085, 7686.615669216629, 7810.492317999312, 7860.0, 7860.84278314419], "value": 6752.908879881689, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.1147968096469, 505.37773746274456, 520.4249304518506, 530.5702381071335, 532.5207313417924, 6187.164732761076, 6313.273973493044, 6393.808185754429, 6428.807135785923, 6431.228252609693, 6104.1759362486055, 6235.220597425322, 6278.309385967551, 6326.588771729835, 6349.698156958379, 5001.3998320201445, 5007.649464537484, 5013.473585273548, 5020.1967685170475, 5074.238864170386, 4838.612910967132, 5055.974720126413, 5074.670146440488, 5083.847484575469, 5092.770825312869, 4997.625178111638, 5023.799048038074, 5042.546170844624, 5049.823256186034, 5075.7715902784275, 4962.734502701788, 4978.352814134176, 5003.849884503471, 5005.27423523587, 5052.242163675442, 4466.3523789050405, 4478.686491945565, 4523.231050721385, 4533.5013148553635, 4565.634749220055, 6181.598196117283, 6227.657478838662, 6228.688565571703, 6229.719662615194, 6311.684415779193, 5765.106408506688, 5769.509591684712, 5771.163181338724, 5810.564207684399, 5821.825345239649, 6583.736650533973, 6618.735250590019, 6661.400473957318, 6675.232348279961, 6692.732290708414, 4849.951500484985, 4853.538913803183, 4869.342638743759, 4891.584215341701, 4916.754162291871, 6815.625140617264, 6893.310668933117, 6921.542852287415, 7054.800683883701, 7081.468889833283, 5033.840313678435, 5054.797808087709, 5070.391553013662, 5071.137906555894, 5093.286939828427, 5027.773750181251, 5061.44324124346, 5113.1052065888325, 5124.538791508782, 5147.845564633067, 5674.517665998354, 5689.544836413077, 5711.857203569861, 5725.793555608872, 5754.251947246915, 6568.325077105307, 6704.731810727614, 6710.261871194165, 6787.219469760942, 6790.626515541594, 1643.1291415549779, 1684.2168391697674, 1733.21138881811, 1742.9019717577844, 1767.0, 1198.72429341252, 1217.5190799634095, 1228.5945637939637, 1233.9758000859265, 1246.7194881151668, 5630.943690563124, 5633.605647604629, 5669.4047125051275, 5783.88888055865, 5849.239598852207, 6613.933860661332, 6622.701978410983, 6727.553575981187, 6801.795946121624, 6811.863762724718, 5758.280214973129, 5770.509506691894, 5776.249087618583, 5816.789619944394, 5822.883542329129, 3557.0, 3604.8377822998027, 3605.0987253186704, 3622.42041273395, 3628.32875917956, 6452.387023232879, 6528.738850446024, 6536.477081833442, 6537.869242615121, 6592.044153597702, 6865.588064716133, 6889.173299204128, 6937.375636192767, 6938.820400531922, 7001.474889383317, 6478.574713563009, 6495.772647957298, 6516.413522782972, 6562.606243625302, 6573.671316434255, 6721.697523611351, 6785.794616749477, 6871.0, 6926.134233220848, 6931.82670433223, 6636.469082473487, 6732.562383445061, 6742.898856517254, 6752.358525940028, 6808.217055038734, 6331.240251169889, 6351.587146835441, 6391.0, 6439.130717353098, 6511.186101737283, 4934.383202099738, 4934.703917764946, 4949.158643030765, 4982.775775090057, 4986.6010719143105, 6743.865122697421, 6781.966090169518, 6783.559068660521, 6808.727650894107, 6844.315568443315, 6570.8357291068105, 6621.139251897415, 6621.238557565942, 6622.768203112868, 6648.767293144717, 6368.172137622079, 6476.287608362982, 6476.579022363531, 6539.411452969255, 6555.672216389257, 6169.691515424301, 6274.1843560336865, 6357.55497115216, 6394.232692076795, 6449.3873082057125, 6534.967325163534, 6562.146920900253, 6594.538382313192, 6653.669266146693, 6670.83322916931, 6280.842978925563, 6291.030086232008, 6355.269144048493, 6358.618482891041, 6405.455536279468], "value": 5085.103514984263, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [869.3740506835077, 879.6085741844879, 894.879191309173, 902.62992173209, 907.2515174980639, 18411.53971150722, 19126.0, 19136.04319784012, 19136.617267654656, 19211.0, 18408.631827363446, 18692.719609205873, 18754.156062977163, 18774.249030038816, 18818.43544693658, 943.79708362702, 964.2045312617084, 965.840636295014, 967.3615413826841, 969.6896992962255, 969.8496733006396, 979.7550612346913, 981.3523074770653, 981.4307701533129, 983.9065288797589, 987.2940847294183, 1014.5941623350645, 1024.359775140537, 1028.3367228137847, 1032.9070383665469, 1021.8518314844343, 1028.9434081125535, 1029.217794476196, 1033.9379637221753, 1035.8964103589656, 742.6323969635065, 748.4872862089486, 748.7940816275513, 748.9213632568557, 750.4071783291248, 18998.0, 19770.0, 19920.70118948217, 19949.102290396822, 20087.899560502105, 21729.456763581038, 22002.559948801096, 22027.33917982463, 22362.329130126123, 22377.664335034988, 17050.48848534546, 17155.142242887807, 17304.567385815328, 17368.0, 17396.21717022737, 3736.0, 3737.8504859805576, 3762.021874312671, 3765.548134223911, 3769.736118471708, 122667.0, 135068.0, 136889.31555342258, 137056.999999999, 138337.0, 15556.377744890078, 16160.83839161617, 17094.145292735564, 17505.56236094108, 17603.471895843144, 30310.54534181989, 31288.21779455532, 31506.212344691565, 31604.841975790434, 31702.36595268082, 9165.450072995642, 9207.26341892647, 9208.861867071999, 9249.445033298023, 9277.536123193813, 4246.44796176495, 4249.660027197817, 4261.829526818892, 4263.829446822092, 4298.247806633889, 5442.755076021588, 5507.531859791882, 5545.500904918576, 5550.694711790809, 5555.472230138131, 1046.1160319529986, 1074.9355038697704, 1094.9671509854718, 1097.9560817567208, 1100.6863044032411, 664.4119953840885, 681.9556728812711, 682.9248782634006, 683.3269229808634, 702.125853312629, 11684.88315116838, 12010.519579216909, 12257.455511659666, 12895.0, 13495.59513214605, 667.4693618573245, 672.2638710611873, 674.6120980436269, 690.1132045321738, 691.0877641513155, 5496.4503549645515, 5606.374151496102, 5656.2929633795775, 5671.489565939003, 5713.628614140068, 118957.4052129751, 119125.0, 119134.0, 119378.4031079839, 119849.0, 101137.49431252797, 105759.0, 106845.0, 108804.0, 108818.0, 90515.0, 90589.09410905938, 91595.0, 95672.52163739137, 95859.04140958501, 10382.688519344432, 10572.312799667998, 10614.787704245717, 10689.198310126927, 10894.945525272324, 41606.0, 42537.78731106325, 42932.57067429286, 43265.783671081444, 43453.78273108614, 54010.0, 55611.44388556063, 55855.44144558503, 55884.72057639686, 56664.0, 16233.10717910479, 16308.755368669243, 16358.427455039016, 16430.0, 16587.66824663524, 16778.748318775473, 16980.23588938476, 17139.400120995706, 17301.0, 17508.47474575765, 15441.768373474632, 15635.374585016472, 15694.293756780744, 15694.372225110868, 15925.0, 16701.91649041747, 16762.748558771385, 16806.495805125865, 16928.830711692724, 16951.915240423717, 15994.840051599334, 16029.198540073183, 16240.02559846413, 16307.510774676779, 16551.586210344358, 11917.94041029824, 11971.221870578385, 12080.45637946277, 12348.0, 12379.071569632133, 12191.51233950632, 12234.327112008925, 12605.495780169058, 12662.303573303558, 12727.045471589483], "value": 8963.156815445154, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1469.1999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 107.0, 116.0, 107.0, 112.0, 118.0, 110.0, 112.0, 113.0, 116.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 122.0, 122.0, 122.0, 165.0, 120.0, 122.0, 120.0, 122.0, 124.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 174.0, 164.0, 174.0, 171.0, 163.0, 166.0, 163.0, 165.0], "value": 168.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 77.0, 75.0, 74.0, 80.0, 78.0, 78.0, 74.0, 75.0, 76.0], "value": 76.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 79.0, 82.0, 81.0, 91.0, 80.0, 79.0, 78.0, 88.0], "value": 82.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 116.0, 122.0, 115.0, 114.0, 123.0, 114.0, 121.0, 114.0, 122.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 151.0, 151.0, 148.0, 149.0, 153.0, 152.0, 156.0, 149.0, 155.0], "value": 151.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 105.0, 100.0, 96.0, 103.0, 96.0, 94.0, 94.0, 96.0, 98.0], "value": 98.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 56.0, 53.0, 53.0, 55.0, 55.0, 54.0, 54.0, 56.0, 56.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 38.0, 43.0, 39.0, 46.0, 39.0, 45.0, 47.0, 38.0, 38.0], "value": 41.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 78.0, 80.0, 75.0, 88.0, 77.0, 84.0, 87.0, 79.0, 77.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 109.0, 112.0, 119.0, 117.0, 125.0, 112.0, 118.0, 115.0, 116.0], "value": 115.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 181.0, 170.0, 167.0, 177.0, 170.0, 179.0, 177.0, 170.0, 169.0], "value": 174.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 78.0, 66.0, 71.0, 66.0, 72.0, 64.0, 64.0, 69.0], "value": 68.6, "unit": "score"}]}]} 15:59:15 INFO - Return code: 0 15:59:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:59:15 INFO - # TBPL SUCCESS # 15:59:15 INFO - Running post-action listener: _package_coverage_data 15:59:15 INFO - Running post-action listener: _resource_record_post_action 15:59:15 INFO - [mozharness: 2017-09-19 22:59:15.203206Z] Finished run-tests step (success) 15:59:15 INFO - Running post-run listener: _resource_record_post_run 15:59:15 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 21106688; Write bytes: 390729728; Read time: 10916; Write time: 252188 15:59:15 INFO - TinderboxPrint: CPU usage
13.3% 15:59:15 INFO - TinderboxPrint: I/O read bytes / time
21,106,688 / 10,916 15:59:15 INFO - TinderboxPrint: I/O write bytes / time
390,729,728 / 252,188 15:59:15 INFO - TinderboxPrint: CPU idle
8,282.5 (86.7%) 15:59:15 INFO - TinderboxPrint: CPU user
1,202.6 (12.6%) 15:59:15 INFO - TinderboxPrint: Swap in / out
0 / 0 15:59:15 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 86016; Write bytes: 168873984; Read time: 140; Write time: 134492 15:59:15 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:59:15 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 18776064; Write bytes: 221855744; Read time: 10572; Write time: 117696 15:59:15 INFO - Running post-run listener: _upload_blobber_files 15:59:15 INFO - Blob upload gear active. 15:59:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:59:15 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 15:59:15 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'] 15:59:15 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 15:59:16 INFO - (blobuploader) - INFO - Open directory for files ... 15:59:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:59:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:59:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:17 INFO - (blobuploader) - INFO - Done attempting. 15:59:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:59:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:59:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:21 INFO - (blobuploader) - INFO - Done attempting. 15:59:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:59:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:59:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:22 INFO - (blobuploader) - INFO - Done attempting. 15:59:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:59:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:59:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:23 INFO - (blobuploader) - INFO - Done attempting. 15:59:23 INFO - (blobuploader) - INFO - Iteration through files over. 15:59:23 INFO - Return code: 0 15:59:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:59:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:59:23 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e70204ebc29df962637e1fcec269e1aa5f358df739232f923d4087fa2f81fcface07fd7d73b94b84d59650dbe87dac4a9b76fed5591a6db4cefb0b506670cc28", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af3a4281138a49b99371b47716bf6ff6ca55953be1e9b0a42a9560e48ccaf4b006e35686f89988b37b9909d04e087af4686d3b35594820545c0766e0c0c31ea8", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c81f1a66cbfe265f2c3588cd55822216ae0936cb7280edb2a197f882e6363765627c959d3d6490f6ab23f2430a180af927666ca9ee0838b9b5411788c0e3bdd", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b35fc2a801ad67471a0cd21296d21e477139b7a85f3dd0855f10d829b7a382b7ead319955aac251309d324845f2c2bd48f8a32a24f0317e4a818c6a09dc46414"} 15:59:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:59:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:59:23 INFO - Contents: 15:59:23 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e70204ebc29df962637e1fcec269e1aa5f358df739232f923d4087fa2f81fcface07fd7d73b94b84d59650dbe87dac4a9b76fed5591a6db4cefb0b506670cc28", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af3a4281138a49b99371b47716bf6ff6ca55953be1e9b0a42a9560e48ccaf4b006e35686f89988b37b9909d04e087af4686d3b35594820545c0766e0c0c31ea8", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c81f1a66cbfe265f2c3588cd55822216ae0936cb7280edb2a197f882e6363765627c959d3d6490f6ab23f2430a180af927666ca9ee0838b9b5411788c0e3bdd", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b35fc2a801ad67471a0cd21296d21e477139b7a85f3dd0855f10d829b7a382b7ead319955aac251309d324845f2c2bd48f8a32a24f0317e4a818c6a09dc46414"} 15:59:23 INFO - Running post-run listener: copy_logs_to_upload_dir 15:59:23 INFO - Copying logs to upload dir... 15:59:23 INFO - mkdir: /builds/slave/test/build/upload/logs 15:59:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.079834 ========= master_lag: 0.24 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-09-19 15:59:23.145974) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-19 15:59:23.150791) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998 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/e70204ebc29df962637e1fcec269e1aa5f358df739232f923d4087fa2f81fcface07fd7d73b94b84d59650dbe87dac4a9b76fed5591a6db4cefb0b506670cc28", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af3a4281138a49b99371b47716bf6ff6ca55953be1e9b0a42a9560e48ccaf4b006e35686f89988b37b9909d04e087af4686d3b35594820545c0766e0c0c31ea8", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c81f1a66cbfe265f2c3588cd55822216ae0936cb7280edb2a197f882e6363765627c959d3d6490f6ab23f2430a180af927666ca9ee0838b9b5411788c0e3bdd", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b35fc2a801ad67471a0cd21296d21e477139b7a85f3dd0855f10d829b7a382b7ead319955aac251309d324845f2c2bd48f8a32a24f0317e4a818c6a09dc46414"} build_url:https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011315 build_url: 'https://queue.taskcluster.net/v1/task/fNRiXVaMT5ClVdEE5NyfTw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e70204ebc29df962637e1fcec269e1aa5f358df739232f923d4087fa2f81fcface07fd7d73b94b84d59650dbe87dac4a9b76fed5591a6db4cefb0b506670cc28", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af3a4281138a49b99371b47716bf6ff6ca55953be1e9b0a42a9560e48ccaf4b006e35686f89988b37b9909d04e087af4686d3b35594820545c0766e0c0c31ea8", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c81f1a66cbfe265f2c3588cd55822216ae0936cb7280edb2a197f882e6363765627c959d3d6490f6ab23f2430a180af927666ca9ee0838b9b5411788c0e3bdd", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b35fc2a801ad67471a0cd21296d21e477139b7a85f3dd0855f10d829b7a382b7ead319955aac251309d324845f2c2bd48f8a32a24f0317e4a818c6a09dc46414"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-19 15:59:23.179810) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-19 15:59:23.180115) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1505860261.72777-1390387998 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004480 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-19 15:59:23.263081) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-19 15:59:23.266384) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-19 15:59:23.266696) ========= ========= Total master_lag: 0.44 =========