builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-023 starttime: 1487597979.55 results: success (0) revision: 66739e3ff97463f071b578a6d05871c88da28cc7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 05:39:39.546107) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-20 05:39:39.546618) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 05:39:39.546916) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-20 05:39:39.564161) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 05:39:39.564696) ========= 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=6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-20 05:39:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 32.1M=0s 2017-02-20 05:39:40 (32.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.158129 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 05:39:39.737828) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 05:39:39.738142) ========= 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=6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015214 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 05:39:39.788283) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-20 05:39:39.788641) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 66739e3ff97463f071b578a6d05871c88da28cc7 --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 66739e3ff97463f071b578a6d05871c88da28cc7 --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=6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-20 05:39:40,515 truncating revision to first 12 chars 2017-02-20 05:39:40,515 Setting DEBUG logging. 2017-02-20 05:39:40,515 attempt 1/10 2017-02-20 05:39:40,515 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/66739e3ff974?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-20 05:39:41,575 unpacking tar archive at: mozilla-central-66739e3ff974/testing/mozharness/ program finished with exit code 0 elapsedTime=1.427388 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-20 05:39:41.228710) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 05:39:41.229020) ========= script_repo_revision: 66739e3ff97463f071b578a6d05871c88da28cc7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-20 05:39:41.229395) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 05:39:41.229654) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-20 05:39:41.236836) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-02-20 05:39:41.237113) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:39:42 INFO - MultiFileLogger online at 20170220 05:39:42 in /builds/slave/test 05:39:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 05:39:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:39:42 INFO - {'append_to_log': False, 05:39:42 INFO - 'base_work_dir': '/builds/slave/test', 05:39:42 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 05:39:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:39:42 INFO - 'branch': 'Firefox-Non-PGO', 05:39:42 INFO - 'buildbot_json_path': 'buildprops.json', 05:39:42 INFO - 'config_files': ('talos/linux_config.py',), 05:39:42 INFO - 'default_actions': ('clobber', 05:39:42 INFO - 'read-buildbot-config', 05:39:42 INFO - 'download-and-extract', 05:39:42 INFO - 'populate-webroot', 05:39:42 INFO - 'create-virtualenv', 05:39:42 INFO - 'install', 05:39:42 INFO - 'run-tests'), 05:39:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:39:42 INFO - 'download_minidump_stackwalk': True, 05:39:42 INFO - 'download_symbols': 'ondemand', 05:39:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:39:42 INFO - 'tooltool.py': '/tools/tooltool.py', 05:39:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:39:42 INFO - '/tools/misc-python/virtualenv.py')}, 05:39:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:39:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:39:42 INFO - 'gecko_profile': False, 05:39:42 INFO - 'gecko_profile_interval': 0, 05:39:42 INFO - 'installer_path': 'installer.exe', 05:39:42 INFO - 'log_level': 'info', 05:39:42 INFO - 'log_name': 'talos', 05:39:42 INFO - 'log_to_console': True, 05:39:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:39:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:39:42 INFO - 'opt_config_files': (), 05:39:42 INFO - 'pip_index': False, 05:39:42 INFO - 'suite': 'dromaeojs', 05:39:42 INFO - 'system_bits': '32', 05:39:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:39:42 INFO - 'title': 'talos-linux64-ix-023', 05:39:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:39:42 INFO - 'use_talos_json': True, 05:39:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:39:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:39:42 INFO - 'work_dir': 'build'} 05:39:42 INFO - [mozharness: 2017-02-20 13:39:42.057944Z] Running clobber step. 05:39:42 INFO - Running pre-action listener: _resource_record_pre_action 05:39:42 INFO - Running main action method: clobber 05:39:42 INFO - rmtree: /builds/slave/test/build 05:39:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:39:43 INFO - Running post-action listener: _resource_record_post_action 05:39:43 INFO - [mozharness: 2017-02-20 13:39:43.039596Z] Finished clobber step (success) 05:39:43 INFO - [mozharness: 2017-02-20 13:39:43.039696Z] Running read-buildbot-config step. 05:39:43 INFO - Running pre-action listener: _resource_record_pre_action 05:39:43 INFO - Running main action method: read_buildbot_config 05:39:43 INFO - Using buildbot properties: 05:39:43 INFO - { 05:39:43 INFO - "project": "", 05:39:43 INFO - "product": "firefox", 05:39:43 INFO - "who": "cbook@mozilla.com", 05:39:43 INFO - "installer_path": "public/build/target.tar.bz2", 05:39:43 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:39:43 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 05:39:43 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 05:39:43 INFO - "stage_platform": "linux64", 05:39:43 INFO - "basedir": "/builds/slave/test", 05:39:43 INFO - "buildnumber": 987, 05:39:43 INFO - "platform": "ubuntu64_hw", 05:39:43 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:39:43 INFO - "slavebuilddir": "test", 05:39:43 INFO - "taskId": "RyQKJTZ3RsmB5QcUdpvsuw", 05:39:43 INFO - "branch": "mozilla-central", 05:39:43 INFO - "script_repo_revision": "production", 05:39:43 INFO - "revision": "66739e3ff97463f071b578a6d05871c88da28cc7", 05:39:43 INFO - "slavename": "talos-linux64-ix-023", 05:39:43 INFO - "repo_path": "mozilla-central" 05:39:43 INFO - } 05:39:43 INFO - Finding installer, test and symbols from parent task. 05:39:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RyQKJTZ3RsmB5QcUdpvsuw'}, attempt #1 05:39:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RyQKJTZ3RsmB5QcUdpvsuw'}, attempt #1 05:39:43 INFO - Task dependencies: bpp91VuURy2KAb9CYu8ZcQ 05:39:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ'}, attempt #1 05:39:44 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.tar.bz2 05:39:44 INFO - Set test_url: https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/test_packages.json 05:39:44 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.crashreporter-symbols.zip 05:39:44 INFO - Running post-action listener: _resource_record_post_action 05:39:44 INFO - [mozharness: 2017-02-20 13:39:44.419564Z] Finished read-buildbot-config step (success) 05:39:44 INFO - [mozharness: 2017-02-20 13:39:44.419781Z] Running download-and-extract step. 05:39:44 INFO - Running pre-action listener: _resource_record_pre_action 05:39:44 INFO - Running main action method: download_and_extract 05:39:44 INFO - mkdir: /builds/slave/test/build/tests 05:39:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:39:44 INFO - trying https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.test_packages.json 05:39:44 INFO - Downloading https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:39:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:39:44 INFO - Downloaded 896 bytes. 05:39:44 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:39:44 INFO - Using the following test package requirements: 05:39:44 INFO - {u'common': [u'target.common.tests.zip'], 05:39:44 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:39:44 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:39:44 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:39:44 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:39:44 INFO - u'mozbase': [u'target.common.tests.zip'], 05:39:44 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:39:44 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:39:44 INFO - u'web-platform': [u'target.common.tests.zip', 05:39:44 INFO - u'target.web-platform.tests.zip'], 05:39:44 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:39:44 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:39:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.common.tests.zip 05:39:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:39:44 INFO - Fetch https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.common.tests.zip into memory 05:39:46 INFO - Expected file size: 57145541 05:39:46 INFO - Obtained file size: 57145541 05:39:55 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:39:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.common.tests.zip 05:39:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:39:55 INFO - Fetch https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.common.tests.zip into memory 05:39:56 INFO - Expected file size: 57145541 05:39:56 INFO - Obtained file size: 57145541 05:40:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.talos.tests.zip 05:40:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:40:07 INFO - Fetch https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.talos.tests.zip into memory 05:40:07 INFO - Expected file size: 14404319 05:40:07 INFO - Obtained file size: 14404319 05:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:08 INFO - trying https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.tar.bz2 05:40:08 INFO - Downloading https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:40:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:40:09 INFO - Downloaded 61528521 bytes. 05:40:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.tar.bz2 05:40:09 INFO - mkdir: /builds/slave/test/properties 05:40:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:40:09 INFO - Writing to file /builds/slave/test/properties/build_url 05:40:09 INFO - Contents: 05:40:09 INFO - build_url:https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.tar.bz2 05:40:09 INFO - Running post-action listener: _resource_record_post_action 05:40:09 INFO - Running post-action listener: set_extra_try_arguments 05:40:09 INFO - [mozharness: 2017-02-20 13:40:09.317491Z] Finished download-and-extract step (success) 05:40:09 INFO - [mozharness: 2017-02-20 13:40:09.317606Z] Running populate-webroot step. 05:40:09 INFO - Running pre-action listener: _resource_record_pre_action 05:40:09 INFO - Running main action method: populate_webroot 05:40:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:40:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:40:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:40:09 INFO - '--tppagecycles', 05:40:09 INFO - '7'], 05:40:09 INFO - 'tests': ['tsvgm']}}, 05:40:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:40:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:40:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:40:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:40:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:40:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:40:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:40:09 INFO - 'talos_options': ['--disable-e10s'], 05:40:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:40:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:40:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:40:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:40:09 INFO - 'talos_options': ['--disable-e10s'], 05:40:09 INFO - 'tests': ['damp', 'tps']}, 05:40:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:40:09 INFO - 'tests': ['damp', 'tps']}, 05:40:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:40:09 INFO - 'tests': ['dromaeo_dom']}, 05:40:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:40:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:40:09 INFO - 'tests': ['basic_compositor_video']}, 05:40:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:40:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:40:09 INFO - 'tests': ['a11yr', 05:40:09 INFO - 'ts_paint', 05:40:09 INFO - 'tpaint', 05:40:09 INFO - 'sessionrestore', 05:40:09 INFO - 'sessionrestore_no_auto_restore', 05:40:09 INFO - 'tabpaint']}, 05:40:09 INFO - 'other-e10s': {'tests': ['a11yr', 05:40:09 INFO - 'ts_paint', 05:40:09 INFO - 'tpaint', 05:40:09 INFO - 'sessionrestore', 05:40:09 INFO - 'sessionrestore_no_auto_restore', 05:40:09 INFO - 'tabpaint']}, 05:40:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:40:09 INFO - 'ts_paint', 05:40:09 INFO - 'tpaint', 05:40:09 INFO - 'sessionrestore', 05:40:09 INFO - 'sessionrestore_no_auto_restore', 05:40:09 INFO - 'tabpaint']}, 05:40:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:40:09 INFO - 'ts_paint', 05:40:09 INFO - 'tpaint', 05:40:09 INFO - 'sessionrestore', 05:40:09 INFO - 'sessionrestore_no_auto_restore', 05:40:09 INFO - 'tabpaint']}, 05:40:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:40:09 INFO - 'tests': ['a11yr', 05:40:09 INFO - 'ts_paint', 05:40:09 INFO - 'tpaint', 05:40:09 INFO - 'sessionrestore', 05:40:09 INFO - 'sessionrestore_no_auto_restore', 05:40:09 INFO - 'tabpaint']}, 05:40:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:40:09 INFO - 'tests': ['a11yr', 05:40:09 INFO - 'ts_paint', 05:40:09 INFO - 'tpaint', 05:40:09 INFO - 'sessionrestore', 05:40:09 INFO - 'sessionrestore_no_auto_restore', 05:40:09 INFO - 'tabpaint']}, 05:40:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:40:09 INFO - 'tests': ['tsvgx', 05:40:09 INFO - 'tsvgr_opacity', 05:40:09 INFO - 'tart', 05:40:09 INFO - 'tscrollx', 05:40:09 INFO - 'cart', 05:40:09 INFO - 'tsvg_static']}, 05:40:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:40:09 INFO - 'tsvgr_opacity', 05:40:09 INFO - 'tart', 05:40:09 INFO - 'tscrollx', 05:40:09 INFO - 'cart', 05:40:09 INFO - 'tsvg_static']}, 05:40:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:40:09 INFO - 'talos_options': ['--disable-e10s'], 05:40:09 INFO - 'tests': ['tp5o']}, 05:40:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:40:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:40:09 INFO - 'talos_options': ['--disable-e10s', 05:40:09 INFO - '--xperf_path', 05:40:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:40:09 INFO - 'tests': ['tp5n']}, 05:40:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:40:09 INFO - 'talos_options': ['--xperf_path', 05:40:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:40:09 INFO - 'tests': ['tp5n']}}, 05:40:09 INFO - 'talos.zip': {'path': '', 05:40:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:40:09 INFO - Running post-action listener: _resource_record_post_action 05:40:09 INFO - [mozharness: 2017-02-20 13:40:09.330848Z] Finished populate-webroot step (success) 05:40:09 INFO - [mozharness: 2017-02-20 13:40:09.330943Z] Running create-virtualenv step. 05:40:09 INFO - Running pre-action listener: _resource_record_pre_action 05:40:09 INFO - Running main action method: create_virtualenv 05:40:09 INFO - Creating virtualenv /builds/slave/test/build/venv 05:40:09 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:40:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:40:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:40:09 INFO - Using real prefix '/usr' 05:40:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:40:09 INFO - Installing distribute.............................................................................................................................................................................................done. 05:40:11 INFO - Installing pip.................done. 05:40:11 INFO - Return code: 0 05:40:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:40:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:40:12 INFO - Reading from file tmpfile_stdout 05:40:12 INFO - Output received: 05:40:12 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:40:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x16c11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195c290>, '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 0x195cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d040>, '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': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:40:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:40:12 INFO - Using env: {'DISPLAY': ':0', 05:40:12 INFO - 'HOME': '/home/cltbld', 05:40:12 INFO - 'LANG': 'en_US.UTF-8', 05:40:12 INFO - 'LANGUAGE': 'en_US:en', 05:40:12 INFO - 'LOGNAME': 'cltbld', 05:40:12 INFO - 'MAIL': '/var/mail/cltbld', 05:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:12 INFO - 'MOZ_NO_REMOTE': '1', 05:40:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:12 INFO - 'NO_EM_RESTART': '1', 05:40:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:12 INFO - 'PWD': '/builds/slave/test', 05:40:12 INFO - 'SHELL': '/bin/bash', 05:40:12 INFO - 'SHLVL': '1', 05:40:12 INFO - 'TERM': 'linux', 05:40:12 INFO - 'TMOUT': '86400', 05:40:12 INFO - 'USER': 'cltbld', 05:40:12 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 05:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:12 INFO - '_': '/tools/buildbot/bin/python'} 05:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:12 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:40:12 INFO - Cleaning up... 05:40:12 INFO - Return code: 0 05:40:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x16c11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195c290>, '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 0x195cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d040>, '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': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:40:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:40:12 INFO - Using env: {'DISPLAY': ':0', 05:40:12 INFO - 'HOME': '/home/cltbld', 05:40:12 INFO - 'LANG': 'en_US.UTF-8', 05:40:12 INFO - 'LANGUAGE': 'en_US:en', 05:40:12 INFO - 'LOGNAME': 'cltbld', 05:40:12 INFO - 'MAIL': '/var/mail/cltbld', 05:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:12 INFO - 'MOZ_NO_REMOTE': '1', 05:40:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:12 INFO - 'NO_EM_RESTART': '1', 05:40:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:12 INFO - 'PWD': '/builds/slave/test', 05:40:12 INFO - 'SHELL': '/bin/bash', 05:40:12 INFO - 'SHLVL': '1', 05:40:12 INFO - 'TERM': 'linux', 05:40:12 INFO - 'TMOUT': '86400', 05:40:12 INFO - 'USER': 'cltbld', 05:40:12 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 05:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:12 INFO - '_': '/tools/buildbot/bin/python'} 05:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:12 INFO - Downloading/unpacking psutil>=3.1.1 05:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:40:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:40:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:40:14 INFO - Installing collected packages: psutil 05:40:14 INFO - Running setup.py install for psutil 05:40:14 INFO - building 'psutil._psutil_linux' extension 05:40:14 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:40:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:40:14 INFO - building 'psutil._psutil_posix' extension 05:40:14 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:40:15 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:40:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:40:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:40:15 INFO - Successfully installed psutil 05:40:15 INFO - Cleaning up... 05:40:15 INFO - Return code: 0 05:40:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x16c11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195c290>, '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 0x195cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d040>, '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': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:40:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:40:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:40:15 INFO - Using env: {'DISPLAY': ':0', 05:40:15 INFO - 'HOME': '/home/cltbld', 05:40:15 INFO - 'LANG': 'en_US.UTF-8', 05:40:15 INFO - 'LANGUAGE': 'en_US:en', 05:40:15 INFO - 'LOGNAME': 'cltbld', 05:40:15 INFO - 'MAIL': '/var/mail/cltbld', 05:40:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:15 INFO - 'MOZ_NO_REMOTE': '1', 05:40:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:15 INFO - 'NO_EM_RESTART': '1', 05:40:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:15 INFO - 'PWD': '/builds/slave/test', 05:40:15 INFO - 'SHELL': '/bin/bash', 05:40:15 INFO - 'SHLVL': '1', 05:40:15 INFO - 'TERM': 'linux', 05:40:15 INFO - 'TMOUT': '86400', 05:40:15 INFO - 'USER': 'cltbld', 05:40:15 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 05:40:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:15 INFO - '_': '/tools/buildbot/bin/python'} 05:40:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:40:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:40:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:40:17 INFO - Installing collected packages: mozsystemmonitor 05:40:17 INFO - Running setup.py install for mozsystemmonitor 05:40:17 INFO - Successfully installed mozsystemmonitor 05:40:17 INFO - Cleaning up... 05:40:17 INFO - Return code: 0 05:40:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x16c11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195c290>, '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 0x195cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d040>, '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': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:40:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:40:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:40:17 INFO - Using env: {'DISPLAY': ':0', 05:40:17 INFO - 'HOME': '/home/cltbld', 05:40:17 INFO - 'LANG': 'en_US.UTF-8', 05:40:17 INFO - 'LANGUAGE': 'en_US:en', 05:40:17 INFO - 'LOGNAME': 'cltbld', 05:40:17 INFO - 'MAIL': '/var/mail/cltbld', 05:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:17 INFO - 'MOZ_NO_REMOTE': '1', 05:40:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:17 INFO - 'NO_EM_RESTART': '1', 05:40:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:17 INFO - 'PWD': '/builds/slave/test', 05:40:17 INFO - 'SHELL': '/bin/bash', 05:40:17 INFO - 'SHLVL': '1', 05:40:17 INFO - 'TERM': 'linux', 05:40:17 INFO - 'TMOUT': '86400', 05:40:17 INFO - 'USER': 'cltbld', 05:40:17 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 05:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:17 INFO - '_': '/tools/buildbot/bin/python'} 05:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:17 INFO - Downloading/unpacking jsonschema==2.5.1 05:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:40:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:20 INFO - Downloading functools32-3.2.3-2.tar.gz 05:40:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:40:20 INFO - warning: no files found matching '*.txt' 05:40:20 INFO - no previously-included directories found matching 'build' 05:40:20 INFO - no previously-included directories found matching 'dist' 05:40:20 INFO - no previously-included directories found matching '.git*' 05:40:20 INFO - Installing collected packages: jsonschema, functools32 05:40:20 INFO - Running setup.py install for functools32 05:40:20 INFO - warning: no files found matching '*.txt' 05:40:20 INFO - no previously-included directories found matching 'build' 05:40:20 INFO - no previously-included directories found matching 'dist' 05:40:20 INFO - no previously-included directories found matching '.git*' 05:40:20 INFO - Successfully installed jsonschema functools32 05:40:20 INFO - Cleaning up... 05:40:20 INFO - Return code: 0 05:40:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:40:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:20 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x16c11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195c290>, '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 0x195cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d040>, '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': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:40:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:40:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:40:20 INFO - Using env: {'DISPLAY': ':0', 05:40:20 INFO - 'HOME': '/home/cltbld', 05:40:20 INFO - 'LANG': 'en_US.UTF-8', 05:40:20 INFO - 'LANGUAGE': 'en_US:en', 05:40:20 INFO - 'LOGNAME': 'cltbld', 05:40:20 INFO - 'MAIL': '/var/mail/cltbld', 05:40:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:20 INFO - 'MOZ_NO_REMOTE': '1', 05:40:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:20 INFO - 'NO_EM_RESTART': '1', 05:40:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:20 INFO - 'PWD': '/builds/slave/test', 05:40:20 INFO - 'SHELL': '/bin/bash', 05:40:20 INFO - 'SHLVL': '1', 05:40:20 INFO - 'TERM': 'linux', 05:40:20 INFO - 'TMOUT': '86400', 05:40:20 INFO - 'USER': 'cltbld', 05:40:20 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 05:40:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:20 INFO - '_': '/tools/buildbot/bin/python'} 05:40:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:40:20 INFO - Cleaning up... 05:40:20 INFO - Return code: 0 05:40:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:40:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:20 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x16c11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195c290>, '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 0x195cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d040>, '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': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:40:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:40:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:40:20 INFO - Using env: {'DISPLAY': ':0', 05:40:20 INFO - 'HOME': '/home/cltbld', 05:40:20 INFO - 'LANG': 'en_US.UTF-8', 05:40:20 INFO - 'LANGUAGE': 'en_US:en', 05:40:20 INFO - 'LOGNAME': 'cltbld', 05:40:20 INFO - 'MAIL': '/var/mail/cltbld', 05:40:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:20 INFO - 'MOZ_NO_REMOTE': '1', 05:40:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:20 INFO - 'NO_EM_RESTART': '1', 05:40:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:20 INFO - 'PWD': '/builds/slave/test', 05:40:20 INFO - 'SHELL': '/bin/bash', 05:40:20 INFO - 'SHLVL': '1', 05:40:20 INFO - 'TERM': 'linux', 05:40:20 INFO - 'TMOUT': '86400', 05:40:20 INFO - 'USER': 'cltbld', 05:40:20 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 05:40:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:20 INFO - '_': '/tools/buildbot/bin/python'} 05:40:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:20 INFO - Downloading/unpacking blobuploader==1.2.4 05:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:22 INFO - Downloading blobuploader-1.2.4.tar.gz 05:40:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:40:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:40:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:40:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:40:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:23 INFO - Downloading docopt-0.6.1.tar.gz 05:40:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:40:23 INFO - Installing collected packages: blobuploader, requests, docopt 05:40:23 INFO - Running setup.py install for blobuploader 05:40:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:40:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:40:23 INFO - Running setup.py install for requests 05:40:24 INFO - Running setup.py install for docopt 05:40:24 INFO - Successfully installed blobuploader requests docopt 05:40:24 INFO - Cleaning up... 05:40:24 INFO - Return code: 0 05:40:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:24 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x16c11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195c290>, '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 0x195cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d040>, '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': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:40:24 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:40:24 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:40:24 INFO - Using env: {'DISPLAY': ':0', 05:40:24 INFO - 'HOME': '/home/cltbld', 05:40:24 INFO - 'LANG': 'en_US.UTF-8', 05:40:24 INFO - 'LANGUAGE': 'en_US:en', 05:40:24 INFO - 'LOGNAME': 'cltbld', 05:40:24 INFO - 'MAIL': '/var/mail/cltbld', 05:40:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:24 INFO - 'MOZ_NO_REMOTE': '1', 05:40:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:24 INFO - 'NO_EM_RESTART': '1', 05:40:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:24 INFO - 'PWD': '/builds/slave/test', 05:40:24 INFO - 'SHELL': '/bin/bash', 05:40:24 INFO - 'SHLVL': '1', 05:40:24 INFO - 'TERM': 'linux', 05:40:24 INFO - 'TMOUT': '86400', 05:40:24 INFO - 'USER': 'cltbld', 05:40:24 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 05:40:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:24 INFO - '_': '/tools/buildbot/bin/python'} 05:40:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:40:24 INFO - Running setup.py (path:/tmp/pip-BpDB94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:40:24 INFO - Running setup.py (path:/tmp/pip-_ArD89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:40:24 INFO - Running setup.py (path:/tmp/pip-SE2T9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:40:24 INFO - Running setup.py (path:/tmp/pip-oUec8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:40:24 INFO - Running setup.py (path:/tmp/pip-_pj2BT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:40:24 INFO - Running setup.py (path:/tmp/pip-Cy2iJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:40:24 INFO - Running setup.py (path:/tmp/pip-rToiKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:40:24 INFO - Running setup.py (path:/tmp/pip-jTUu8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:40:25 INFO - Running setup.py (path:/tmp/pip-qs4BVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:40:25 INFO - Running setup.py (path:/tmp/pip-JWu6DM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:40:25 INFO - Running setup.py (path:/tmp/pip-k8iTZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:40:25 INFO - Running setup.py (path:/tmp/pip-qobzOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:40:25 INFO - Running setup.py (path:/tmp/pip-RaOexE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:40:25 INFO - Running setup.py (path:/tmp/pip-eM_s_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:25 INFO - Running setup.py (path:/tmp/pip-5bBH12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:40:25 INFO - Running setup.py (path:/tmp/pip-Ga9h7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:40:25 INFO - Running setup.py (path:/tmp/pip-ov8uxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:40:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:40:25 INFO - Running setup.py install for manifestparser 05:40:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:40:25 INFO - Running setup.py install for mozcrash 05:40:25 INFO - Running setup.py install for mozdebug 05:40:25 INFO - Running setup.py install for mozdevice 05:40:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:40:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:40:26 INFO - Running setup.py install for mozfile 05:40:26 INFO - Running setup.py install for mozhttpd 05:40:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:40:26 INFO - Running setup.py install for mozinfo 05:40:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:40:26 INFO - Running setup.py install for mozInstall 05:40:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:40:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:40:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:40:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:40:26 INFO - Running setup.py install for mozleak 05:40:26 INFO - Running setup.py install for mozlog 05:40:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:40:26 INFO - Running setup.py install for moznetwork 05:40:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:40:26 INFO - Running setup.py install for mozprocess 05:40:26 INFO - Running setup.py install for mozprofile 05:40:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:40:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:40:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:40:27 INFO - Running setup.py install for mozrunner 05:40:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:40:27 INFO - Running setup.py install for mozscreenshot 05:40:27 INFO - Running setup.py install for moztest 05:40:27 INFO - Running setup.py install for mozversion 05:40:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:40:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:40:27 INFO - Cleaning up... 05:40:27 INFO - Return code: 0 05:40:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:27 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x16c11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195c290>, '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 0x195cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d040>, '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': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:40:27 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:40:27 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:40:27 INFO - Using env: {'DISPLAY': ':0', 05:40:27 INFO - 'HOME': '/home/cltbld', 05:40:27 INFO - 'LANG': 'en_US.UTF-8', 05:40:27 INFO - 'LANGUAGE': 'en_US:en', 05:40:27 INFO - 'LOGNAME': 'cltbld', 05:40:27 INFO - 'MAIL': '/var/mail/cltbld', 05:40:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:27 INFO - 'MOZ_NO_REMOTE': '1', 05:40:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:27 INFO - 'NO_EM_RESTART': '1', 05:40:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:27 INFO - 'PWD': '/builds/slave/test', 05:40:27 INFO - 'SHELL': '/bin/bash', 05:40:27 INFO - 'SHLVL': '1', 05:40:27 INFO - 'TERM': 'linux', 05:40:27 INFO - 'TMOUT': '86400', 05:40:27 INFO - 'USER': 'cltbld', 05:40:27 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 05:40:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:27 INFO - '_': '/tools/buildbot/bin/python'} 05:40:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:40:27 INFO - Running setup.py (path:/tmp/pip-BEi2QC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:40:27 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:40:27 INFO - Running setup.py (path:/tmp/pip-d_ccM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:40:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:40:27 INFO - Running setup.py (path:/tmp/pip-KRI0Bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:40:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:40:27 INFO - Running setup.py (path:/tmp/pip-Fxaagx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:40:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:40:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:40:27 INFO - Running setup.py (path:/tmp/pip-uus6uD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:40:28 INFO - Running setup.py (path:/tmp/pip-feM1ne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:40:28 INFO - Running setup.py (path:/tmp/pip-4KPSSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:40:28 INFO - Running setup.py (path:/tmp/pip-Q4fprJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:40:28 INFO - Running setup.py (path:/tmp/pip-KL4TXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:40:28 INFO - Running setup.py (path:/tmp/pip-4tVHJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:40:28 INFO - Running setup.py (path:/tmp/pip-LAgUAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:40:28 INFO - Running setup.py (path:/tmp/pip-q9eFE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:40:28 INFO - Running setup.py (path:/tmp/pip-nXNbI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:40:28 INFO - Running setup.py (path:/tmp/pip-JvAwB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:28 INFO - Running setup.py (path:/tmp/pip-VTaujK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:40:28 INFO - Running setup.py (path:/tmp/pip-NcDqqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:40:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:40:28 INFO - Running setup.py (path:/tmp/pip-E34mCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:40:28 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:40:28 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:40:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:40:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:40:30 INFO - Downloading blessings-1.6.tar.gz 05:40:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:40:31 INFO - Installing collected packages: blessings 05:40:31 INFO - Running setup.py install for blessings 05:40:31 INFO - Successfully installed blessings 05:40:31 INFO - Cleaning up... 05:40:31 INFO - Return code: 0 05:40:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:40:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:40:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:40:31 INFO - Reading from file tmpfile_stdout 05:40:31 INFO - Current package versions: 05:40:31 INFO - argparse == 1.2.1 05:40:31 INFO - blessings == 1.6 05:40:31 INFO - blobuploader == 1.2.4 05:40:31 INFO - docopt == 0.6.1 05:40:31 INFO - functools32 == 3.2.3-2 05:40:31 INFO - jsonschema == 2.5.1 05:40:31 INFO - manifestparser == 1.1 05:40:31 INFO - mozInstall == 1.12 05:40:31 INFO - mozcrash == 1.0 05:40:31 INFO - mozdebug == 0.1 05:40:31 INFO - mozdevice == 0.49 05:40:31 INFO - mozfile == 1.2 05:40:31 INFO - mozhttpd == 0.7 05:40:31 INFO - mozinfo == 0.9 05:40:31 INFO - mozleak == 0.1 05:40:31 INFO - mozlog == 3.4 05:40:31 INFO - moznetwork == 0.27 05:40:31 INFO - mozprocess == 0.25 05:40:31 INFO - mozprofile == 0.28 05:40:31 INFO - mozrunner == 6.13 05:40:31 INFO - mozscreenshot == 0.1 05:40:31 INFO - mozsystemmonitor == 0.3 05:40:31 INFO - moztest == 0.8 05:40:31 INFO - mozversion == 1.4 05:40:31 INFO - psutil == 3.1.1 05:40:31 INFO - requests == 1.2.3 05:40:31 INFO - wsgiref == 0.1.2 05:40:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:40:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x16c11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195c290>, '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 0x195cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d040>, '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': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:40:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:40:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:40:31 INFO - Using env: {'DISPLAY': ':0', 05:40:31 INFO - 'HOME': '/home/cltbld', 05:40:31 INFO - 'LANG': 'en_US.UTF-8', 05:40:31 INFO - 'LANGUAGE': 'en_US:en', 05:40:31 INFO - 'LOGNAME': 'cltbld', 05:40:31 INFO - 'MAIL': '/var/mail/cltbld', 05:40:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:31 INFO - 'MOZ_NO_REMOTE': '1', 05:40:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:31 INFO - 'NO_EM_RESTART': '1', 05:40:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:31 INFO - 'PWD': '/builds/slave/test', 05:40:31 INFO - 'SHELL': '/bin/bash', 05:40:31 INFO - 'SHLVL': '1', 05:40:31 INFO - 'TERM': 'linux', 05:40:31 INFO - 'TMOUT': '86400', 05:40:31 INFO - 'USER': 'cltbld', 05:40:31 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 05:40:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:31 INFO - '_': '/tools/buildbot/bin/python'} 05:40:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:40:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:40:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:40:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:40:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:40:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:40:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:40:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:40:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:40:31 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:40:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:40:31 INFO - Cleaning up... 05:40:31 INFO - Return code: 0 05:40:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:40:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x16c11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x195eb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195c290>, '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 0x195cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d040>, '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': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:40:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:40:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:40:31 INFO - Using env: {'DISPLAY': ':0', 05:40:31 INFO - 'HOME': '/home/cltbld', 05:40:31 INFO - 'LANG': 'en_US.UTF-8', 05:40:31 INFO - 'LANGUAGE': 'en_US:en', 05:40:31 INFO - 'LOGNAME': 'cltbld', 05:40:31 INFO - 'MAIL': '/var/mail/cltbld', 05:40:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:31 INFO - 'MOZ_NO_REMOTE': '1', 05:40:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:31 INFO - 'NO_EM_RESTART': '1', 05:40:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:31 INFO - 'PWD': '/builds/slave/test', 05:40:31 INFO - 'SHELL': '/bin/bash', 05:40:31 INFO - 'SHLVL': '1', 05:40:31 INFO - 'TERM': 'linux', 05:40:31 INFO - 'TMOUT': '86400', 05:40:31 INFO - 'USER': 'cltbld', 05:40:31 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 05:40:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:31 INFO - '_': '/tools/buildbot/bin/python'} 05:40:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:40:31 INFO - Cleaning up... 05:40:31 INFO - Return code: 0 05:40:31 INFO - Running post-action listener: _resource_record_post_action 05:40:31 INFO - Running post-action listener: _start_resource_monitoring 05:40:31 INFO - Starting resource monitoring. 05:40:31 INFO - [mozharness: 2017-02-20 13:40:31.894401Z] Finished create-virtualenv step (success) 05:40:31 INFO - [mozharness: 2017-02-20 13:40:31.894873Z] Running install step. 05:40:31 INFO - Running pre-action listener: _resource_record_pre_action 05:40:31 INFO - Running main action method: install 05:40:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:40:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:40:32 INFO - Reading from file tmpfile_stdout 05:40:32 INFO - Detecting whether we're running mozinstall >=1.0... 05:40:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:40:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:40:32 INFO - Reading from file tmpfile_stdout 05:40:32 INFO - Output received: 05:40:32 INFO - Usage: mozinstall [options] installer 05:40:32 INFO - Options: 05:40:32 INFO - -h, --help show this help message and exit 05:40:32 INFO - -d DEST, --destination=DEST 05:40:32 INFO - Directory to install application into. [default: 05:40:32 INFO - "/builds/slave/test"] 05:40:32 INFO - --app=APP Application being installed. [default: firefox] 05:40:32 INFO - mkdir: /builds/slave/test/build/application 05:40:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:40:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:40:47 INFO - Reading from file tmpfile_stdout 05:40:47 INFO - Output received: 05:40:47 INFO - /builds/slave/test/build/application/firefox/firefox 05:40:47 INFO - Running post-action listener: _resource_record_post_action 05:40:47 INFO - [mozharness: 2017-02-20 13:40:47.592941Z] Finished install step (success) 05:40:47 INFO - [mozharness: 2017-02-20 13:40:47.593221Z] Running run-tests step. 05:40:47 INFO - Running pre-action listener: _resource_record_pre_action 05:40:47 INFO - Running main action method: run_tests 05:40:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:40:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:40:47 INFO - Python 2.7.3 05:40:47 INFO - Return code: 0 05:40:47 INFO - grabbing minidump binary from tooltool 05:40:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:40:47 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195c290>, '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 0x195cb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x195d040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:40:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:40:47 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:40:47 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:40:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:40:47 INFO - Return code: 0 05:40:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:40:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:40:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:40:47 INFO - ENV: RUST_BACKTRACE is now 1 05:40:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:40:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:40:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:40:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-023 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:40:47 INFO - Using env: {'DISPLAY': ':0', 05:40:47 INFO - 'HOME': '/home/cltbld', 05:40:47 INFO - 'LANG': 'en_US.UTF-8', 05:40:47 INFO - 'LANGUAGE': 'en_US:en', 05:40:47 INFO - 'LOGNAME': 'cltbld', 05:40:47 INFO - 'MAIL': '/var/mail/cltbld', 05:40:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:40:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:40:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:47 INFO - 'MOZ_NO_REMOTE': '1', 05:40:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:40:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:47 INFO - 'NO_EM_RESTART': '1', 05:40:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:47 INFO - 'PWD': '/builds/slave/test', 05:40:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:40:47 INFO - 'RUST_BACKTRACE': '1', 05:40:47 INFO - 'SHELL': '/bin/bash', 05:40:47 INFO - 'SHLVL': '1', 05:40:47 INFO - 'TERM': 'linux', 05:40:47 INFO - 'TMOUT': '86400', 05:40:47 INFO - 'USER': 'cltbld', 05:40:47 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299', 05:40:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:47 INFO - '_': '/tools/buildbot/bin/python'} 05:40:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:40:47 INFO - mozversion application_buildid: 20170220130357 05:40:47 INFO - mozversion application_changeset: 66739e3ff97463f071b578a6d05871c88da28cc7 05:40:47 INFO - mozversion application_display_name: Nightly 05:40:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:40:47 INFO - mozversion application_name: Firefox 05:40:47 INFO - mozversion application_remotingname: firefox 05:40:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:40:47 INFO - mozversion application_vendor: Mozilla 05:40:47 INFO - mozversion application_version: 54.0a1 05:40:47 INFO - mozversion platform_buildid: 20170220130357 05:40:47 INFO - mozversion platform_changeset: 66739e3ff97463f071b578a6d05871c88da28cc7 05:40:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:40:47 INFO - mozversion platform_version: 54.0a1 05:40:47 INFO - using testdate: 1487598047 05:40:47 INFO - actual date: 1487598047 05:40:47 INFO - starting webserver on 'localhost:42953' 05:40:47 INFO - SUITE-START | Running 2 tests 05:40:47 INFO - TEST-START | dromaeo_css 05:40:47 INFO - operating with platform_type : linux_ 05:40:47 INFO - Initialising browser for dromaeo_css test... 05:40:47 INFO - TEST-INFO | started process 27979 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNuKV2x/profile http://localhost:42953/getInfo.html) 05:40:50 INFO - PROCESS | 27979 | Iterating over profile /tmp/tmpNuKV2x/profile 05:40:51 INFO - PROCESS | 27979 | __metrics Screen width/height:1600/1200 05:40:51 INFO - PROCESS | 27979 | colorDepth:24 05:40:51 INFO - PROCESS | 27979 | Browser inner width/height: 1024/697 05:40:51 INFO - PROCESS | 27979 | __metrics 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/extensions = Mon Feb 20 2017 05:40:48 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/compatibility.ini = Mon Feb 20 2017 05:40:48 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/blocklist.xml = Mon Feb 20 2017 05:40:48 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/pluginreg.dat = Mon Feb 20 2017 05:40:50 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/safebrowsing = Mon Feb 20 2017 05:40:52 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/storage.sqlite = Mon Feb 20 2017 05:40:52 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/frequencyCap.json = Mon Feb 20 2017 05:40:50 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/.parentlock = Mon Feb 20 2017 05:40:47 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/content-prefs.sqlite = Mon Feb 20 2017 05:40:50 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/localstore.rdf = Mon Feb 20 2017 05:40:07 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/places.sqlite = Mon Feb 20 2017 05:40:52 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/minidumps = Mon Feb 20 2017 05:40:47 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/places.sqlite-shm = Mon Feb 20 2017 05:40:49 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/permissions.sqlite = Mon Feb 20 2017 05:40:49 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/startupCache = Mon Feb 20 2017 05:40:48 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/thumbnails = Mon Feb 20 2017 05:40:50 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/sessionCheckpoints.json = Mon Feb 20 2017 05:40:51 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/webappsstore.sqlite-shm = Mon Feb 20 2017 05:40:52 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/cookies.sqlite = Mon Feb 20 2017 05:40:51 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/bookmarkbackups = Mon Feb 20 2017 05:40:50 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/addons.json = Mon Feb 20 2017 05:40:50 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/mimeTypes.rdf = Mon Feb 20 2017 05:40:51 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | Stat failure 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/webappsstore.sqlite = Mon Feb 20 2017 05:40:51 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/extensions.ini = Mon Feb 20 2017 05:40:48 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/cert8.db = Mon Feb 20 2017 05:40:48 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/directoryLinks.json = Mon Feb 20 2017 05:40:50 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/safebrowsing-backup = Mon Feb 20 2017 05:40:50 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/webappsstore.sqlite-wal = Mon Feb 20 2017 05:40:52 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | Stat failure 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/crashes = Mon Feb 20 2017 05:40:47 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/places.sqlite-wal = Mon Feb 20 2017 05:40:52 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/gmp = Mon Feb 20 2017 05:40:48 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/cache2 = Mon Feb 20 2017 05:40:48 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/secmod.db = Mon Feb 20 2017 05:40:48 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/prefs.js = Mon Feb 20 2017 05:40:07 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/extensions.json = Mon Feb 20 2017 05:40:50 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/key3.db = Mon Feb 20 2017 05:40:48 GMT-0800 (PST) 05:40:52 INFO - PROCESS | 27979 | No birth date. Using mtime. 05:40:52 INFO - PROCESS | 27979 | Using date: /tmp/tmpNuKV2x/profile/user.js = Mon Feb 20 2017 05:40:47 GMT-0800 (PST) 05:40:53 INFO - PROCESS | 27979 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:40:53 INFO - PROCESS | 27979 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:40:54 INFO - TEST-INFO | 27979: exit 0 05:40:54 INFO - Browser initialized. 05:40:54 INFO - Running cycle 1/1 for dromaeo_css test... 05:40:54 INFO - TEST-INFO | started process 28072 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNuKV2x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:41:06 INFO - PROCESS | 28072 | RSS: Main: 146423808 05:41:06 INFO - PROCESS | 28072 | 05:44:12 INFO - PROCESS | 28072 | Cycle 1(1): loaded http://localhost:42953/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42953/tests/dromaeo/cssquery-ext.html) 05:44:14 INFO - PROCESS | 28072 | RSS: Main: 272523264 05:44:14 INFO - PROCESS | 28072 | 05:45:55 INFO - PROCESS | 28072 | ************************* 05:45:55 INFO - PROCESS | 28072 | A coding exception was thrown and uncaught in a Task. 05:45:55 INFO - PROCESS | 28072 | 05:45:55 ERROR - PROCESS | 28072 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:45:55 INFO - PROCESS | 28072 | Full stack: 05:45:55 INFO - PROCESS | 28072 | ************************* 05:47:17 INFO - PROCESS | 28072 | Cycle 1(1): loaded http://localhost:42953/tests/dromaeo/cssquery-ext.html (next: http://localhost:42953/tests/dromaeo/cssquery-jquery.html) 05:47:17 INFO - PROCESS | 28072 | RSS: Main: 252465152 05:47:17 INFO - PROCESS | 28072 | 05:50:20 INFO - PROCESS | 28072 | Cycle 1(1): loaded http://localhost:42953/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42953/tests/dromaeo/cssquery-mootools.html) 05:50:20 INFO - PROCESS | 28072 | RSS: Main: 183934976 05:50:20 INFO - PROCESS | 28072 | 05:53:23 INFO - PROCESS | 28072 | Cycle 1(1): loaded http://localhost:42953/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42953/tests/dromaeo/cssquery-prototype.html) 05:53:23 INFO - PROCESS | 28072 | RSS: Main: 182300672 05:53:23 INFO - PROCESS | 28072 | 05:56:26 INFO - PROCESS | 28072 | Cycle 1(1): loaded http://localhost:42953/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42953/tests/dromaeo/cssquery-yui.html) 05:56:27 INFO - PROCESS | 28072 | RSS: Main: 184381440 05:56:27 INFO - PROCESS | 28072 | 05:59:30 INFO - PROCESS | 28072 | Cycle 1(1): loaded http://localhost:42953/tests/dromaeo/cssquery-yui.html (next: http://localhost:42953/tests/dromaeo/cssquery-dojo.html) 05:59:30 INFO - PROCESS | 28072 | RSS: Main: 281485312 05:59:30 INFO - PROCESS | 28072 | 05:59:30 INFO - PROCESS | 28072 | __start_tp_report 05:59:30 INFO - PROCESS | 28072 | _x_x_mozilla_page_load 05:59:30 INFO - PROCESS | 28072 | _x_x_mozilla_page_load_details 05:59:30 INFO - PROCESS | 28072 | |i|pagename|runs| 05:59:30 INFO - PROCESS | 28072 | |0;dojo.html;2585.237354980281;2615.3853844346586;2630.51335502932;2639.445716399556;2652.3634327761333;4651.7906694198755;4714.9528504714945;4760;4799.1841386964215;4818.542238487347;4746.62027037837;4785.114753770555;4798.136335459616;4804.471508134111;4809.254565542338;4352.630026447748;4367.366731823884;4383.452068491439;4392.890177745566;4449.66627502937;4410.095930334273;4448.399466072087;4466.665000124987;4492.483364413101;4544.750038747868;4319.805608747614;4479.350494178326;4490.348899409567;4544.545545445462;4567.497575266734;4331.870043898688;4401.119776044772;4401.757903315349;4412.867613971586;4418.867433976986;4386.188555117314;4388.539203383662;4389.122175564899;4391.055922976543;4401.031773009932;4682.859514214579;4708.199606066977;4733.881652958704;4746.288056791474;4746.905061898778;5127.794888204505;5144.588432925357;5145.125328694131;5151.201563757586;5159.406668233164;5132.9743351283005;5137.588992880598;5147.794088236466;5147.819826306061;5184.14461613837;4758.357621721023;4805.1590971579835;4805.567498925114;4814.133455977888;4843.5398637129765;5088.465711100281;5145.562627176657;5155.793768249303;5210.895782084337;5274.525292723673;4946.752662366867;4990.10178167926;5028.7737051832755;5032.47159048302;5096.566791822736;5124.436312005676;5135.922961155586;5137.897242055138;5138.04946084968;5138.100832354323;4905.950940490621;5097.46476619957;5102.3877134743325;5108.719020453911;5154.5103215194495;4943.307936888838;5016.523430274118;5016.648834581544;5017.67385119966;5021.297018417425;4254.361845723104;4255.6169944705125;4256.7658778766845;4258.829646814154;4300.376445415397;3755.6995440364703;3772.4718539404507;3784.791836449022;3798.7150963677836;3806.657400833938;4461.709988850715;4466.843660471868;4475.194464996267;4475.619572336323;4514.706544074625;5041.722705251246;5089.694618322913;5142.871428214325;5182.663126896739;5189.195674670432;4411.316245981878;4421.358902959053;4421.977890110529;4424.867253982386;4425.915650665544;4580.335851301543;4592;4592.127495775792;4593.242115051046;4595.586397224266;4817.78319975595;4822.445418776885;4822.734749588806;4823.131836269506;4824.179889418737;5091.745412729275;5120.641555091257;5134.922976155286;5135.229715542699;5143.0999575073465;4754.096721622881;4773.9045219096115;4793.065352256276;4805.807767689393;4846.369971903771;4975.626827987843;5026.271190677405;5066.088104141142;5067.949320506747;5072.619553533574;5031.924521132113;5088.135017047185;5093.108705976513;5098.770555324945;5136.794528218829;4780.282957556395;4836;4935.679180853233;4939.185034469416;4939.234418665113;4870.05034018363;4933.876653083702;4971.304017437489;4989.675671081368;5042.268871013755;4903.190973489334;4926.901461970812;4930.852074437773;4931.654784165074;4991.5258050485145;5015.824446144367;5015.899682006267;5016.147254966738;5016.573591244785;5069.518395752398;4727.527247275245;4777.761111944319;4778.665493415428;4829.517048295143;4833.564979151894 05:59:30 INFO - PROCESS | 28072 | |1;ext.html;505.38595606338316;532.281420082888;553.3636318234029;563.9830805075846;634.3973225435834;26706.19881403556;37171.814140929295;39101.80449097754;39254;41137.58862411375;38871.41692874609;39390.80304598473;39440;39846;39892;6764.7970560883;7506.0992680878235;7687.500312479699;7695.923040769577;7714.6142692865415;6721.697523611425;9376.81246375072;9578.042195780436;9599.232061435068;9876.95061524695;9603;9659.32384733069;9671.854922176173;9675.419474831497;9743.561539730728;9612.567434465465;9670.323077384586;9673.129418352382;9683.12851843334;9687.854682179774;9474.668386606505;9540.666076687283;9547.474888881108;9553.379030363005;9557.426554406758;35743.106422339646;35789;35811;35963;36080.45879311812;3446.913827154316;3506.842192101336;3508.6491350864962;3536.504889315498;3567.3935430976794;27197;27746.167614971582;27833.860830695918;28016.159515214575;28497.57253641197;3288.1615188126875;3373.510840928076;3407.727381809449;3532.7867225189157;3559.57285125784;221672;223076;223512.8824355884;224593;224899;38241.426378604345;39047.80476097601;39221.80389098093;39226.607733922865;39284.6071539281;60349.69825150846;61457.692711536154;61628;61881;61900.38099618946;13076.607701768962;13234.602961911158;13512.391942362618;13517.932410337886;13530;10298.279120461646;10406.219533535015;10417.114545263737;10418.947905260426;10419.791604167875;18240.270389184283;18306.725399119023;18320.04598505491;18421.26314947414;18446.723299150523;7029.683664235122;7386;7386.593737344498;7436.702531898663;7438.776836694908;768.9692412303557;784.2392878907451;791.1487120138903;815.48216882279;818.7380038387778;24348.391290217533;24863.378415539755;26545.336366590604;27188.39652535513;27306.180814575197;922.1101636920404;928.6656803550717;937.554661535765;953.4327075390132;971.2909576009553;12132.939335303266;12974.610761677164;13025.088243823031;13148.539801106916;13168.275744833934;323529;324983;325299;325671;326340;41678.37482437766;42045.36931946023;42061.15877682429;42063.7896810514;42120.78939605282;1127.413744852672;1178.9882101178878;1179.2511755035614;1182.0838849891059;1182.6511179202157;21057.473563161042;21085.89457052705;21240.78759212388;21727.782722173208;21932.232371866907;37288.44067339046;40081;41164.794176028925;41282.793586033076;45207;313648;315598;316725;317076;317906;26437.86781066159;27285.454290913673;31938.36123277475;32956.1760955978;33073.669263308024;35396.646033539335;35771.82114089413;35927.28145437024;36633;36672.266554668226;29528.557071644373;29716.702832971394;30448.84775576108;31490;32195.678043220207;30516;30929.381412371178;31405;31441.213969650027;31467.527987079753;29731.256718582208;30421.69578304277;30777.230569235948;30847.537286940264;31283.06150815479;26084;28725.42549149047;28789.856050719613;28802.567961481014;29115.272118197216;22214.006654609817;22239.888800556542;22246.332610021724;22732.545349092594;22833.31500055001 05:59:30 INFO - PROCESS | 28072 | |2;jquery.html;356.2429836597231;376.5030160188551;381.313635456179;388.67739775985933;406.4553498312261;12029.338386388745;12795.232286062832;13038.1525200862;16618.169091545406;17636.735448968277;17194.656106877854;18201.089945502707;18314.45056648303;18461.630767384646;18498;4802.8078876844975;5094.617903657222;5095.872603184932;5098.617603679721;5102.923456148161;3403.7277017838637;3538.7699799512948;3577.37395955708;5037.1940489521685;5088.1095808233595;3584.551931008624;5785.421457854223;5808.912866307009;5879.353271140171;5888.32284287306;5677.290338707661;5813.128030795372;5870.559708021894;5871.324797648283;5872.970635146797;5084.51697088776;5084.898302033976;5113.054084994251;5113.565346945514;5114.923276150861;21244.256451024296;21644.675329870064;21647.45881352963;21653.566928661498;21844.672329915065;1755.3768412213608;1782.315711652489;1793.2826869252403;1806.9096545172688;1814.2198854492563;13353.799693004612;13476.932615336958;13594.184348938996;13648.317584120754;14110.435582576685;1670.1064930262373;1681.6636672665516;1685.7218558937761;1710.931562737489;1770.929162833498;71381.64309178421;71433.6428317855;72457.63771181164;72899.27100729031;73546.63226683886;9476;9500.23998080152;9638.614455421704;9730.36752611078;9789.363691360039;12369.814452783216;12402;13580;13591.728165436834;13618.114822536503;6878.65606719662;6908.171019477594;6983.952407138868;7119.786406407816;7198.856022879618;6619.404253617101;6683.732650694015;6858.794236172923;6869.484788640872;6880.827979300558;6157.907631385533;6237.719302631393;6270.843228919313;6337;6440.3881631245895;1753.2373417563401;1762.8589712822943;1771.2649250560873;1804.7834259888898;1820.7359932809668;955.6464108279981;955.9617615295449;969.0793745941384;972.9464879431557;1001.8747656542932;15690.137042462546;17271.77677903367;17999.100044997696;18058.187381567863;18651.720224196648;938.2400255792829;948.4309414351334;949.7008442340779;949.7293271417612;949.943003419797;9759.756006099762;10272.794544109225;10345.534450949724;10346.430946298025;10395.272330936761;438364;452480;472396;474849;475322;74811.62594186995;75780.72657820274;75868.62065689637;75887;77163.35458067742;876.268315956171;886.4238245140581;889.8709687095335;893.4237416866085;896.4755617963565;14457.27713614336;15384;15489.845101549292;15600.687986239984;15966.521004369422;36128;36835.815820920725;37166.814165929;37431.81284093562;37750.2449950994;47082.529174707815;47384.289235660806;47647.52352476431;47711.22887711201;47886.76056619694;7266.6729997151315;7421.814454638461;7433.851322973402;7668.079830420385;8236.217559331853;12349.814752778535;12378.690532736753;12627.74744505123;12652.367381630698;12994.480220791062;10148.188144948534;10267;10315.742106447398;10412.895871041193;10564.26050176511;9919.305648604528;10006.899931000597;10011.949940250252;10024.649137280161;10060.849087263843;10782.191335650015;10893.346399216072;10984.231103822656;11009.559617615205;11284.379359135326;7534.924650753423;7550.207228240955;7647.235276472308;7689.269519395649;8084.9191508086515;10640.84038739404;10698.572057117628;10849.945750271198;10945.507452164831;10973.506192221528 05:59:30 INFO - PROCESS | 28072 | |3;mootools.html;1337.5853485419514;1338.6051114921097;1354.329606844612;1357.3891748713081;1360.4762166565874;6872.347127022931;7250.854982900339;7254.746083887065;7255.165655949568;7267.63661816909;6754.392104710575;6970.930290697092;6998.895016574756;7111.502194846363;7123.394511466531;2027.7769445361034;2066.1012459580056;2072.896355182235;2074.5850829834017;2075.616011037955;1744.2586900567208;2041.4794227471973;2087.7390326209224;2093.5603523260133;2100.002498813059;1751.6146447781468;2013.2047841102756;2064.3394113883564;2069.4102180878454;2082.6355387807075;1976.2490253703645;1976.5651556657513;1979.5842872996689;1980.2178139634907;1985.0372569303947;1932.3719791067958;1955.9315423960236;2040.6224848403092;2043.2746375036945;2043.7751847296786;6136.846578835521;6333.050042493617;7052.188998265163;7223.963880180618;7232.240614735482;4283.336082907154;4366.650667946588;4392.604665580081;4412.514623391425;4415.0066235097165;2780.3883145707914;2844.5733140028956;2873.6838947715733;2909.4472050310374;2909.723576260252;1057.434272664121;1060.6287799270272;1062.8352605346183;1108.312846035455;1119.6025410979148;9511.286653501014;9538.809223815555;9619.90380096204;9628.518574071268;9651.71044868655;3543.627919068461;3704.925901482009;3706.9073273168387;3786.2238241160494;3787.886363409102;7743.612819359009;7793.961030194926;7808.336291415179;7812.453128281079;7817.022872140982;4279.957200428018;4341.587549182823;4351.369051487517;4361.498427680857;4364.650827933821;3764.6047165047275;3884.1843212925037;3924;3939.1333906540517;3940.9211815763524;3090.3664748726906;3101.9608431175357;3181.984090079535;3224.806511609264;3229.822359770188;917.9954100229456;926.3561824531886;933.09955892564;939.3236869453982;947.5688561704408;737.5500944423898;737.7491652838061;753.7550296153772;760.0271652285084;789.1792535762858;4276.144771045803;4587.9541204588195;4612.685384665421;4639.582437580567;4640.257558790578;471.6156332588929;477.77067007836433;479.966402351839;484.2663365002025;484.4840245138916;3043.269615292314;3068.6317641883115;3144.9685503145133;3168.5722427472456;3188.0913939527586;7701.229877012254;8031.55826429552;8047.637856296364;8177.591120443954;8382.413231073766;8455;8715.302775777922;8741.956290218508;8747.693830716022;8753.387262891536;5831.883362332815;6386.36136386367;6782.796516104525;6787.997540725498;6839.521233513706;4821.397325334333;4929;4931.802727890844;4945.554900059012;4975.726335051462;7775.416843736629;7862.52824830512;7928.167542407964;8028.87956680662;8087.595620219083;7957.80105497344;8035.799105022421;8086.6361013755695;8107.324582887515;8111.797205069921;6145.846353841011;6329.968350158404;6415.743370265137;6416.101745755504;6427.58220715652;6717.966410167918;6725.73097076131;6792.796216113525;6804.659767011531;6804.693788779417;6060.636361818305;6170.167027451416;6202.751889924352;6206.751729930752;6335.873282534231;6111.266648002268;6153.446189842935;6191.1641928339095;6201.348858369806;6207;5333.12003519415;5368.033753924331;5374.086405311028;5376.059189641719;5392.892142157213;4084.3056680365003;4148.771817550064;4241.066965267636;4255.276602977563;4322.055075216228;4079.408485769606;4083.8774836754947;4135.379693046068;4247.214265360918;4305.203537345601 05:59:30 INFO - PROCESS | 28072 | |4;prototype.html;318.6383454778827;329.359395974829;332.92009917619765;333.10893360261326;335.2540597171293;4826.662133650641;4857.247126695359;4880.511948805118;4896.632752543555;4896.902061958759;4807.495213002628;4811.446683631383;4815.820124069599;4836.46798852126;4845.200541910581;3619.9095022624383;3750.268697603968;3751.8311675974646;3756.8309426075757;3907.8436862525464;3771.2457508498273;3772.849086036555;3778.886633401002;3784.924301513968;3789.450529673196;3764.171882185915;3768.3782175941074;3771.283456143338;3775.7734535927793;3777.8666400080006;3611.4402267648557;3725.18046029873;3736.7757934523743;3752.7185461090526;3769.8492060317553;3222.774405791572;3224.661410551905;3247.3342964692183;3254.251522149919;3274.1323549259464;4637.744924029177;4692.301926392304;4704.776758042934;4709.529047095263;4718.197906355894;4244.639205667522;4259;4270.765107919063;4273.166732487166;4281.186574550857;4859.927101093486;5051.646384753069;5098.770555325019;5116.8998665287145;5119.030962042007;3561.519194908706;3563.8396272167815;3591.299696559172;3596.1549035976786;3597.6582224688614;4994.9500504994485;5236.3978142513715;5256.684598924076;5268.972550352682;5275.788968441258;3412.1640198151117;3536.0806190390167;3541.327147841902;3602.7117830573493;3606.945895811565;3638.599754027055;3640.8179591020344;3646.5441819772527;3647.94528082079;3652.8904132876055;4005.0187704012706;4233.809478573472;4239.494979282372;4271.615554600101;4279.614834664895;4959.603231741452;4969.602431805447;4983.775730092155;5040.873978150502;5061.8228362007885;1210.9333986630643;1231.8952889004356;1233.481937586216;1239.6405042537563;1241.522014024589;804.6700852650386;842.9704960326359;845.6532821543137;852.0457088061411;857.5926434943477;4307.698461107755;4326.805293761789;4331.631811296075;4335.34969754533;4340.479142502857;5068.670536415195;5086.3133476980875;5105.540501354896;5126.9750846999705;5148.794048238028;4239.51245606753;4264.466941632296;4293.3130699088;4359.869203923888;4392.868213953587;2527;2693.4412308307187;2694.6362241097595;2708.390612112259;2713.905013324524;4766.928496072562;4774.188387974053;4786.7845946932475;4796.352492413549;4802.958742534409;4799.58438236823;5173.32746742921;5200.541639809484;5217.608679349064;5222.503862133091;4741.217699079613;4741.66808323421;4748.62011039123;4780.904381912412;4803.615710743132;5009.073321435546;5044.419891712353;5063.734066483379;5069.822556210515;5082;4671.579557839811;4943.184374578154;4950.232713929347;4980.452150263541;5002.549770520671;4759.190937540696;4762.404699412574;4780.545848144421;4783.947531543195;4797.832075877328;3405.2338223900415;3479.1650003999366;3509.649035096572;3545.9290814184087;3647.2705458908854;5136.9743151284;5201.609879258995;5270.472952704698;5278.181881808326;5283.630145889904;5092.261622064854;5107.208382700687;5118.795248190031;5121.667091639031;5143.382794064736;4808.533397313853;5023.598112151092;5054.1660625997765;5074.82238121664;5077.136886729191;4990.950090499048;4997.675151115166;5038.571721403721;5042.29407882904;5055.5955523557295;5150.304708864255;5154.561862241601;5162.535371816554;5168.53483186515;5173.767180476812;4819.855404337875;4973.2096445280395;4999.600031997359;5012.548870601663;5018.899622007612 05:59:30 INFO - PROCESS | 28072 | |5;yui.html;656.9694921655641;680.0479328939484;729.0121884846034;747.9775606731798;770.3952397368067;12454.439550220239;13271.800922986164;13349.265790381525;13430.932845335772;13472.797908031374;13105.803412948813;13286.13640113392;13305.268210248432;13307.733845323088;13316.800247996263;791.1574173505213;794.9483283586549;819.4223072733738;824.1634740738126;830.8213734047179;819.5328662662284;820.2576668115365;820.2781552234026;821.1788211788212;821.4003777242616;847.9872801907976;848.4485084694967;849.1211595998143;849.3502470609989;849.3502470609989;843.9198276163754;844.995775021121;845.2139510255429;845.3786466946789;845.5898889038812;622.0513716582203;622.5735371270649;623.0125251476416;623.2769986815273;623.3672822085587;13861.09902856311;14091.0840795349;14134.15195088298;14146.504872329419;14437.855621443756;11497;12333.383330833512;12417.130800843854;12476.937615311956;12905.741885162339;10068.848967265498;10166.339187952759;10220.795584088351;10305.484725763681;10657.52041158142;3072.2472994116583;3104.5343198520177;3106.6641344221975;3246.724028457584;3250.203700093469;57262.42737572654;57675.711621442046;58844;58932.705336473475;59205.303310569354;9445.66940157105;9523.952380238194;9553.61785528571;9572.664956726481;10092.4029223727;16308.592285192728;16332.346706131859;16362.181890905407;16413;16424;6374.139491168726;6376;6516.478681705452;6605.30644282353;6673;3330.58367704037;3475.669811367916;3569.839357228931;3585.6414358563934;3611.8555257789394;3014.8191108533556;3100.55042018906;3101.1006808025872;3104.642966058932;3109.937801244008;469.6008392866105;475.08546048855817;487.34208818095993;489.7159647404497;490.15693008026136;576.9913451298233;604.1722839709626;605.2736715940889;614.6834380294063;617.7776000639766;7680.654370553339;7991.600419978978;8313.584320783937;8318.37612179089;8613;602.8944934636421;631.2834932351722;647.6891092275735;647.821848991531;652.5953908576618;3685.3366394048794;3710.7216958728204;3835.980820095882;4002.8398864045694;4237.406763053175;50602.746986264836;60983.475413115026;61874.381256187764;64425;65520.171974203295;52794;55821;55962;56418;57309.14036289459;50191.74904125456;50415;50817.49182508201;52241.21638175506;52284.47715522872;8842;9246.630134794803;9288.959302703346;9325.813483730422;9393.154616084585;32065.198370040936;33497.83251083729;33693.49459758057;34109;35103.171112088654;40672.593274068066;41180.58819411768;42241.78879105585;43082.138357232056;44602.3309650362;10248.231382646181;10306.845397319195;10310.484475776331;10322.225833062701;10660.147188225079;12354.567590134302;12377.443015064524;12482.438290276777;12637.97760522127;12816.359182040675;10488.370697758159;10645.467726613793;10882.836757448804;11195.832062518906;11297.378644174649;11000.22998390137;11046.33721976684;11266;11333.093352531943;11756.94121529387;11541.653750387502;11760.941195293968;11866.050715942876;11959.521619135137;12293.062159442075;9671.806563868824;9995.639419646315;10039.49802509857;10190.1338386238;10269.435181064815;9249.260059195383;9693.616047214047;9708.320417570701;9889.930333773986;9939.044112159007 05:59:30 INFO - PROCESS | 28072 | __end_tp_report 05:59:30 INFO - PROCESS | 28072 | __start_cc_report 05:59:30 INFO - PROCESS | 28072 | _x_x_mozilla_cycle_collect,1524 05:59:30 INFO - PROCESS | 28072 | __end_cc_report 05:59:30 INFO - PROCESS | 28072 | __startTimestamp1487599170967__endTimestamp 05:59:30 INFO - PROCESS | 28072 | 05:59:30 INFO - PROCESS | 28072 | ------- Summary: start ------- 05:59:30 INFO - PROCESS | 28072 | Number of tests: 6 05:59:30 INFO - PROCESS | 28072 | 05:59:30 INFO - PROCESS | 28072 | [#0] dojo.html Cycles:165 Average:4717.02 Median:4818.54 stddev:494.03 (10.3%) stddev-sans-first:466.38 05:59:30 INFO - PROCESS | 28072 | Values: 2585.2 2615.4 2630.5 2639.4 2652.4 4651.8 4715.0 4760.0 4799.2 4818.5 4746.6 4785.1 4798.1 4804.5 4809.3 4352.6 4367.4 4383.5 4392.9 4449.7 4410.1 4448.4 4466.7 4492.5 4544.8 4319.8 4479.4 4490.3 4544.5 4567.5 4331.9 4401.1 4401.8 4412.9 4418.9 4386.2 4388.5 4389.1 4391.1 4401.0 4682.9 4708.2 4733.9 4746.3 4746.9 5127.8 5144.6 5145.1 5151.2 5159.4 5133.0 5137.6 5147.8 5147.8 5184.1 4758.4 4805.2 4805.6 4814.1 4843.5 5088.5 5145.6 5155.8 5210.9 5274.5 4946.8 4990.1 5028.8 5032.5 5096.6 5124.4 5135.9 5137.9 5138.0 5138.1 4906.0 5097.5 5102.4 5108.7 5154.5 4943.3 5016.5 5016.6 5017.7 5021.3 4254.4 4255.6 4256.8 4258.8 4300.4 3755.7 3772.5 3784.8 3798.7 3806.7 4461.7 4466.8 4475.2 4475.6 4514.7 5041.7 5089.7 5142.9 5182.7 5189.2 4411.3 4421.4 4422.0 4424.9 4425.9 4580.3 4592.0 4592.1 4593.2 4595.6 4817.8 4822.4 4822.7 4823.1 4824.2 5091.7 5120.6 5134.9 5135.2 5143.1 4754.1 4773.9 4793.1 4805.8 4846.4 4975.6 5026.3 5066.1 5067.9 5072.6 5031.9 5088.1 5093.1 5098.8 5136.8 4780.3 4836.0 4935.7 4939.2 4939.2 4870.1 4933.9 4971.3 4989.7 5042.3 4903.2 4926.9 4930.9 4931.7 4991.5 5015.8 5015.9 5016.1 5016.6 5069.5 4727.5 4777.8 4778.7 4829.5 4833.6 05:59:30 INFO - PROCESS | 28072 | 05:59:30 INFO - PROCESS | 28072 | [#1] ext.html Cycles:180 Average:43429.17 Median:3507.75 stddev:76652.14 (2185.2%) stddev-sans-first:76799.42 05:59:30 INFO - PROCESS | 28072 | Values: 505.4 532.3 553.4 564.0 634.4 26706.2 37171.8 39101.8 39254.0 41137.6 38871.4 39390.8 39440.0 39846.0 39892.0 6764.8 7506.1 7687.5 7695.9 7714.6 6721.7 9376.8 9578.0 9599.2 9877.0 9603.0 9659.3 9671.9 9675.4 9743.6 9612.6 9670.3 9673.1 9683.1 9687.9 9474.7 9540.7 9547.5 9553.4 9557.4 35743.1 35789.0 35811.0 35963.0 36080.5 3446.9 3506.8 3508.6 3536.5 3567.4 27197.0 27746.2 27833.9 28016.2 28497.6 3288.2 3373.5 3407.7 3532.8 3559.6 221672.0 223076.0 223512.9 224593.0 224899.0 38241.4 39047.8 39221.8 39226.6 39284.6 60349.7 61457.7 61628.0 61881.0 61900.4 13076.6 13234.6 13512.4 13517.9 13530.0 10298.3 10406.2 10417.1 10418.9 10419.8 18240.3 18306.7 18320.0 18421.3 18446.7 7029.7 7386.0 7386.6 7436.7 7438.8 769.0 784.2 791.1 815.5 818.7 24348.4 24863.4 26545.3 27188.4 27306.2 922.1 928.7 937.6 953.4 971.3 12132.9 12974.6 13025.1 13148.5 13168.3 323529.0 324983.0 325299.0 325671.0 326340.0 41678.4 42045.4 42061.2 42063.8 42120.8 1127.4 1179.0 1179.3 1182.1 1182.7 21057.5 21085.9 21240.8 21727.8 21932.2 37288.4 40081.0 41164.8 41282.8 45207.0 313648.0 315598.0 316725.0 317076.0 317906.0 26437.9 27285.5 31938.4 32956.2 33073.7 35396.6 35771.8 35927.3 36633.0 36672.3 29528.6 29716.7 30448.8 31490.0 32195.7 30516.0 30929.4 31405.0 31441.2 31467.5 29731.3 30421.7 30777.2 30847.5 31283.1 26084.0 28725.4 28789.9 28802.6 29115.3 22214.0 22239.9 22246.3 22732.5 22833.3 05:59:30 INFO - PROCESS | 28072 | 05:59:30 INFO - PROCESS | 28072 | [#2] jquery.html Cycles:180 Average:26480.91 Median:385.00 stddev:76001.86 (19741.0%) stddev-sans-first:76189.75 05:59:30 INFO - PROCESS | 28072 | Values: 356.2 376.5 381.3 388.7 406.5 12029.3 12795.2 13038.2 16618.2 17636.7 17194.7 18201.1 18314.5 18461.6 18498.0 4802.8 5094.6 5095.9 5098.6 5102.9 3403.7 3538.8 3577.4 5037.2 5088.1 3584.6 5785.4 5808.9 5879.4 5888.3 5677.3 5813.1 5870.6 5871.3 5873.0 5084.5 5084.9 5113.1 5113.6 5114.9 21244.3 21644.7 21647.5 21653.6 21844.7 1755.4 1782.3 1793.3 1806.9 1814.2 13353.8 13476.9 13594.2 13648.3 14110.4 1670.1 1681.7 1685.7 1710.9 1770.9 71381.6 71433.6 72457.6 72899.3 73546.6 9476.0 9500.2 9638.6 9730.4 9789.4 12369.8 12402.0 13580.0 13591.7 13618.1 6878.7 6908.2 6984.0 7119.8 7198.9 6619.4 6683.7 6858.8 6869.5 6880.8 6157.9 6237.7 6270.8 6337.0 6440.4 1753.2 1762.9 1771.3 1804.8 1820.7 955.6 956.0 969.1 972.9 1001.9 15690.1 17271.8 17999.1 18058.2 18651.7 938.2 948.4 949.7 949.7 949.9 9759.8 10272.8 10345.5 10346.4 10395.3 438364.0 452480.0 472396.0 474849.0 475322.0 74811.6 75780.7 75868.6 75887.0 77163.4 876.3 886.4 889.9 893.4 896.5 14457.3 15384.0 15489.8 15600.7 15966.5 36128.0 36835.8 37166.8 37431.8 37750.2 47082.5 47384.3 47647.5 47711.2 47886.8 7266.7 7421.8 7433.9 7668.1 8236.2 12349.8 12378.7 12627.7 12652.4 12994.5 10148.2 10267.0 10315.7 10412.9 10564.3 9919.3 10006.9 10011.9 10024.6 10060.8 10782.2 10893.3 10984.2 11009.6 11284.4 7534.9 7550.2 7647.2 7689.3 8084.9 10640.8 10698.6 10849.9 10945.5 10973.5 05:59:30 INFO - PROCESS | 28072 | 05:59:30 INFO - PROCESS | 28072 | [#3] mootools.html Cycles:180 Average:4623.58 Median:2706.74 stddev:2548.70 (94.2%) stddev-sans-first:2543.89 05:59:30 INFO - PROCESS | 28072 | Values: 1337.6 1338.6 1354.3 1357.4 1360.5 6872.3 7250.9 7254.7 7255.2 7267.6 6754.4 6970.9 6998.9 7111.5 7123.4 2027.8 2066.1 2072.9 2074.6 2075.6 1744.3 2041.5 2087.7 2093.6 2100.0 1751.6 2013.2 2064.3 2069.4 2082.6 1976.2 1976.6 1979.6 1980.2 1985.0 1932.4 1955.9 2040.6 2043.3 2043.8 6136.8 6333.1 7052.2 7224.0 7232.2 4283.3 4366.7 4392.6 4412.5 4415.0 2780.4 2844.6 2873.7 2909.4 2909.7 1057.4 1060.6 1062.8 1108.3 1119.6 9511.3 9538.8 9619.9 9628.5 9651.7 3543.6 3704.9 3706.9 3786.2 3787.9 7743.6 7794.0 7808.3 7812.5 7817.0 4280.0 4341.6 4351.4 4361.5 4364.7 3764.6 3884.2 3924.0 3939.1 3940.9 3090.4 3102.0 3182.0 3224.8 3229.8 918.0 926.4 933.1 939.3 947.6 737.6 737.7 753.8 760.0 789.2 4276.1 4588.0 4612.7 4639.6 4640.3 471.6 477.8 480.0 484.3 484.5 3043.3 3068.6 3145.0 3168.6 3188.1 7701.2 8031.6 8047.6 8177.6 8382.4 8455.0 8715.3 8742.0 8747.7 8753.4 5831.9 6386.4 6782.8 6788.0 6839.5 4821.4 4929.0 4931.8 4945.6 4975.7 7775.4 7862.5 7928.2 8028.9 8087.6 7957.8 8035.8 8086.6 8107.3 8111.8 6145.8 6330.0 6415.7 6416.1 6427.6 6718.0 6725.7 6792.8 6804.7 6804.7 6060.6 6170.2 6202.8 6206.8 6335.9 6111.3 6153.4 6191.2 6201.3 6207.0 5333.1 5368.0 5374.1 5376.1 5392.9 4084.3 4148.8 4241.1 4255.3 4322.1 4079.4 4083.9 4135.4 4247.2 4305.2 05:59:30 INFO - PROCESS | 28072 | 05:59:30 INFO - PROCESS | 28072 | [#4] prototype.html Cycles:180 Average:4150.33 Median:4764.67 stddev:1215.64 (25.5%) stddev-sans-first:1184.55 05:59:30 INFO - PROCESS | 28072 | Values: 318.6 329.4 332.9 333.1 335.3 4826.7 4857.2 4880.5 4896.6 4896.9 4807.5 4811.4 4815.8 4836.5 4845.2 3619.9 3750.3 3751.8 3756.8 3907.8 3771.2 3772.8 3778.9 3784.9 3789.5 3764.2 3768.4 3771.3 3775.8 3777.9 3611.4 3725.2 3736.8 3752.7 3769.8 3222.8 3224.7 3247.3 3254.3 3274.1 4637.7 4692.3 4704.8 4709.5 4718.2 4244.6 4259.0 4270.8 4273.2 4281.2 4859.9 5051.6 5098.8 5116.9 5119.0 3561.5 3563.8 3591.3 3596.2 3597.7 4995.0 5236.4 5256.7 5269.0 5275.8 3412.2 3536.1 3541.3 3602.7 3606.9 3638.6 3640.8 3646.5 3647.9 3652.9 4005.0 4233.8 4239.5 4271.6 4279.6 4959.6 4969.6 4983.8 5040.9 5061.8 1210.9 1231.9 1233.5 1239.6 1241.5 804.7 843.0 845.7 852.0 857.6 4307.7 4326.8 4331.6 4335.3 4340.5 5068.7 5086.3 5105.5 5127.0 5148.8 4239.5 4264.5 4293.3 4359.9 4392.9 2527.0 2693.4 2694.6 2708.4 2713.9 4766.9 4774.2 4786.8 4796.4 4803.0 4799.6 5173.3 5200.5 5217.6 5222.5 4741.2 4741.7 4748.6 4780.9 4803.6 5009.1 5044.4 5063.7 5069.8 5082.0 4671.6 4943.2 4950.2 4980.5 5002.5 4759.2 4762.4 4780.5 4783.9 4797.8 3405.2 3479.2 3509.6 3545.9 3647.3 5137.0 5201.6 5270.5 5278.2 5283.6 5092.3 5107.2 5118.8 5121.7 5143.4 4808.5 5023.6 5054.2 5074.8 5077.1 4991.0 4997.7 5038.6 5042.3 5055.6 5150.3 5154.6 5162.5 5168.5 5173.8 4819.9 4973.2 4999.6 5012.5 5018.9 05:59:30 INFO - PROCESS | 28072 | 05:59:30 INFO - PROCESS | 28072 | [#5] yui.html Cycles:180 Average:14217.42 Median:481.21 stddev:17335.58 (3602.5%) stddev-sans-first:17354.30 05:59:30 INFO - PROCESS | 28072 | Values: 657.0 680.0 729.0 748.0 770.4 12454.4 13271.8 13349.3 13430.9 13472.8 13105.8 13286.1 13305.3 13307.7 13316.8 791.2 794.9 819.4 824.2 830.8 819.5 820.3 820.3 821.2 821.4 848.0 848.4 849.1 849.4 849.4 843.9 845.0 845.2 845.4 845.6 622.1 622.6 623.0 623.3 623.4 13861.1 14091.1 14134.2 14146.5 14437.9 11497.0 12333.4 12417.1 12476.9 12905.7 10068.8 10166.3 10220.8 10305.5 10657.5 3072.2 3104.5 3106.7 3246.7 3250.2 57262.4 57675.7 58844.0 58932.7 59205.3 9445.7 9524.0 9553.6 9572.7 10092.4 16308.6 16332.3 16362.2 16413.0 16424.0 6374.1 6376.0 6516.5 6605.3 6673.0 3330.6 3475.7 3569.8 3585.6 3611.9 3014.8 3100.6 3101.1 3104.6 3109.9 469.6 475.1 487.3 489.7 490.2 577.0 604.2 605.3 614.7 617.8 7680.7 7991.6 8313.6 8318.4 8613.0 602.9 631.3 647.7 647.8 652.6 3685.3 3710.7 3836.0 4002.8 4237.4 50602.7 60983.5 61874.4 64425.0 65520.2 52794.0 55821.0 55962.0 56418.0 57309.1 50191.7 50415.0 50817.5 52241.2 52284.5 8842.0 9246.6 9289.0 9325.8 9393.2 32065.2 33497.8 33693.5 34109.0 35103.2 40672.6 41180.6 42241.8 43082.1 44602.3 10248.2 10306.8 10310.5 10322.2 10660.1 12354.6 12377.4 12482.4 12638.0 12816.4 10488.4 10645.5 10882.8 11195.8 11297.4 11000.2 11046.3 11266.0 11333.1 11756.9 11541.7 11760.9 11866.1 11959.5 12293.1 9671.8 9995.6 10039.5 10190.1 10269.4 9249.3 9693.6 9708.3 9889.9 9939.0 05:59:30 INFO - PROCESS | 28072 | -------- Summary: end -------- 05:59:30 INFO - PROCESS | 28072 | 05:59:31 INFO - TEST-INFO | 28072: exit 0 05:59:31 INFO - TEST-OK | dromaeo_css | took 1123961ms 05:59:31 INFO - TEST-START | kraken 05:59:31 INFO - operating with platform_type : linux_ 05:59:31 INFO - Initialising browser for kraken test... 05:59:31 INFO - TEST-INFO | started process 28388 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW51HNL/profile http://localhost:42953/getInfo.html) 05:59:35 INFO - PROCESS | 28388 | Iterating over profile /tmp/tmpW51HNL/profile 05:59:35 INFO - PROCESS | 28388 | __metrics Screen width/height:1600/1200 05:59:35 INFO - PROCESS | 28388 | colorDepth:24 05:59:35 INFO - PROCESS | 28388 | Browser inner width/height: 1024/697 05:59:35 INFO - PROCESS | 28388 | __metrics 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/extensions = Mon Feb 20 2017 05:59:32 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/compatibility.ini = Mon Feb 20 2017 05:59:32 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/blocklist.xml = Mon Feb 20 2017 05:59:32 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/pluginreg.dat = Mon Feb 20 2017 05:59:34 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/safebrowsing = Mon Feb 20 2017 05:59:36 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/frequencyCap.json = Mon Feb 20 2017 05:59:34 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/.parentlock = Mon Feb 20 2017 05:59:31 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/content-prefs.sqlite = Mon Feb 20 2017 05:59:34 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/localstore.rdf = Mon Feb 20 2017 05:40:07 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/places.sqlite = Mon Feb 20 2017 05:59:35 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/minidumps = Mon Feb 20 2017 05:59:31 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/places.sqlite-shm = Mon Feb 20 2017 05:59:33 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/permissions.sqlite = Mon Feb 20 2017 05:59:33 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/startupCache = Mon Feb 20 2017 05:59:31 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/thumbnails = Mon Feb 20 2017 05:59:34 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/sessionCheckpoints.json = Mon Feb 20 2017 05:59:36 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/cookies.sqlite = Mon Feb 20 2017 05:59:35 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/bookmarkbackups = Mon Feb 20 2017 05:59:34 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/addons.json = Mon Feb 20 2017 05:59:34 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/mimeTypes.rdf = Mon Feb 20 2017 05:59:35 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | Stat failure 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/webappsstore.sqlite = Mon Feb 20 2017 05:59:35 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | Stat failure 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/extensions.ini = Mon Feb 20 2017 05:59:32 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/cert8.db = Mon Feb 20 2017 05:59:32 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/directoryLinks.json = Mon Feb 20 2017 05:59:34 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/crashes = Mon Feb 20 2017 05:59:31 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/places.sqlite-wal = Mon Feb 20 2017 05:59:36 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/gmp = Mon Feb 20 2017 05:59:32 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/cache2 = Mon Feb 20 2017 05:59:32 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/secmod.db = Mon Feb 20 2017 05:59:32 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/prefs.js = Mon Feb 20 2017 05:40:07 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/extensions.json = Mon Feb 20 2017 05:59:34 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/key3.db = Mon Feb 20 2017 05:59:32 GMT-0800 (PST) 05:59:36 INFO - PROCESS | 28388 | No birth date. Using mtime. 05:59:36 INFO - PROCESS | 28388 | Using date: /tmp/tmpW51HNL/profile/user.js = Mon Feb 20 2017 05:59:31 GMT-0800 (PST) 05:59:37 INFO - PROCESS | 28388 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:59:37 INFO - PROCESS | 28388 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:59:38 INFO - TEST-INFO | 28388: exit 0 05:59:38 INFO - Browser initialized. 05:59:38 INFO - Running cycle 1/1 for kraken test... 05:59:38 INFO - TEST-INFO | started process 28482 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW51HNL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:59:50 INFO - PROCESS | 28482 | RSS: Main: 147365888 05:59:50 INFO - PROCESS | 28482 | 05:59:52 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:42953/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42953/tests/kraken/driver.html?testName=audio-beat-detection) 05:59:53 INFO - PROCESS | 28482 | RSS: Main: 202108928 05:59:53 INFO - PROCESS | 28482 | 05:59:56 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:42953/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42953/tests/kraken/driver.html?testName=audio-dft) 05:59:57 INFO - PROCESS | 28482 | RSS: Main: 181645312 05:59:57 INFO - PROCESS | 28482 | 06:00:00 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:42953/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42953/tests/kraken/driver.html?testName=audio-fft) 06:00:00 INFO - PROCESS | 28482 | RSS: Main: 263790592 06:00:00 INFO - PROCESS | 28482 | 06:00:03 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:42953/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42953/tests/kraken/driver.html?testName=audio-oscillator) 06:00:03 INFO - PROCESS | 28482 | RSS: Main: 175013888 06:00:03 INFO - PROCESS | 28482 | 06:00:06 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:42953/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42953/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:00:06 INFO - PROCESS | 28482 | RSS: Main: 177991680 06:00:06 INFO - PROCESS | 28482 | 06:00:09 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:42953/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42953/tests/kraken/driver.html?testName=imaging-darkroom) 06:00:10 INFO - PROCESS | 28482 | RSS: Main: 217268224 06:00:10 INFO - PROCESS | 28482 | 06:00:13 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:42953/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42953/tests/kraken/driver.html?testName=imaging-desaturate) 06:00:14 INFO - PROCESS | 28482 | RSS: Main: 211787776 06:00:14 INFO - PROCESS | 28482 | 06:00:17 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:42953/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42953/tests/kraken/driver.html?testName=json-parse-financial) 06:00:17 INFO - PROCESS | 28482 | RSS: Main: 213508096 06:00:17 INFO - PROCESS | 28482 | 06:00:19 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:42953/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42953/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:00:20 INFO - PROCESS | 28482 | RSS: Main: 177446912 06:00:20 INFO - PROCESS | 28482 | 06:00:22 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:42953/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42953/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:00:22 INFO - PROCESS | 28482 | RSS: Main: 184586240 06:00:22 INFO - PROCESS | 28482 | 06:00:24 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:42953/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42953/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:00:25 INFO - PROCESS | 28482 | RSS: Main: 192815104 06:00:25 INFO - PROCESS | 28482 | 06:00:28 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:42953/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42953/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:00:29 INFO - PROCESS | 28482 | RSS: Main: 203161600 06:00:29 INFO - PROCESS | 28482 | 06:00:32 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:42953/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42953/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:00:32 INFO - PROCESS | 28482 | RSS: Main: 198115328 06:00:32 INFO - PROCESS | 28482 | 06:00:34 INFO - PROCESS | 28482 | Cycle 1(1): loaded http://localhost:42953/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42953/tests/kraken/driver.html?testName=ai-astar) 06:00:34 INFO - PROCESS | 28482 | RSS: Main: 249749504 06:00:34 INFO - PROCESS | 28482 | 06:00:34 INFO - PROCESS | 28482 | __start_tp_report 06:00:34 INFO - PROCESS | 28482 | _x_x_mozilla_page_load 06:00:34 INFO - PROCESS | 28482 | _x_x_mozilla_page_load_details 06:00:34 INFO - PROCESS | 28482 | |i|pagename|runs| 06:00:34 INFO - PROCESS | 28482 | |0;ai-astar;109;118;115;116;116;118;116;115;117;116 06:00:34 INFO - PROCESS | 28482 | |1;audio-beat-detection;155;128;126;175;126;126;124;125;167;127 06:00:34 INFO - PROCESS | 28482 | |2;audio-dft;162;162;162;166;163;162;162;165;163;164 06:00:34 INFO - PROCESS | 28482 | |3;audio-fft;76;76;76;108;77;78;77;77;100;78 06:00:34 INFO - PROCESS | 28482 | |4;audio-oscillator;84;85;108;84;84;84;99;85;85;110 06:00:34 INFO - PROCESS | 28482 | |5;imaging-gaussian-blur;113;113;113;113;114;113;112;113;113;113 06:00:34 INFO - PROCESS | 28482 | |6;imaging-darkroom;149;149;149;150;149;149;149;149;148;148 06:00:34 INFO - PROCESS | 28482 | |7;imaging-desaturate;96;96;96;96;96;96;95;96;97;96 06:00:34 INFO - PROCESS | 28482 | |8;json-parse-financial;63;62;62;61;63;64;64;62;63;63 06:00:34 INFO - PROCESS | 28482 | |9;json-stringify-tinderbox;66;66;66;65;66;66;65;65;66;67 06:00:34 INFO - PROCESS | 28482 | |10;stanford-crypto-aes;87;88;88;93;87;87;87;87;87;88 06:00:34 INFO - PROCESS | 28482 | |11;stanford-crypto-ccm;139;136;152;156;136;136;134;134;181;159 06:00:34 INFO - PROCESS | 28482 | |12;stanford-crypto-pbkdf2;182;181;186;184;186;182;182;187;185;183 06:00:34 INFO - PROCESS | 28482 | |13;stanford-crypto-sha256-iterative;71;71;70;69;70;70;69;69;69;67 06:00:34 INFO - PROCESS | 28482 | __end_tp_report 06:00:34 INFO - PROCESS | 28482 | __start_cc_report 06:00:34 INFO - PROCESS | 28482 | _x_x_mozilla_cycle_collect,2019 06:00:34 INFO - PROCESS | 28482 | __end_cc_report 06:00:34 INFO - PROCESS | 28482 | __startTimestamp1487599234975__endTimestamp 06:00:34 INFO - PROCESS | 28482 | 06:00:34 INFO - PROCESS | 28482 | ------- Summary: start ------- 06:00:34 INFO - PROCESS | 28482 | Number of tests: 14 06:00:34 INFO - PROCESS | 28482 | 06:00:34 INFO - PROCESS | 28482 | [#0] ai-astar Cycles:10 Average:115.60 Median:116.00 stddev:2.55 (2.2%) stddev-sans-first:1.12 06:00:34 INFO - PROCESS | 28482 | Values: 109.0 118.0 115.0 116.0 116.0 118.0 116.0 115.0 117.0 116.0 06:00:34 INFO - PROCESS | 28482 | 06:00:34 INFO - PROCESS | 28482 | [#1] audio-beat-detection Cycles:10 Average:137.90 Median:127.50 stddev:19.77 (15.5%) stddev-sans-first:19.97 06:00:34 INFO - PROCESS | 28482 | Values: 155.0 128.0 126.0 175.0 126.0 126.0 124.0 125.0 167.0 127.0 06:00:34 INFO - PROCESS | 28482 | 06:00:34 INFO - PROCESS | 28482 | [#2] audio-dft Cycles:10 Average:163.10 Median:163.00 stddev:1.45 (0.9%) stddev-sans-first:1.48 06:00:34 INFO - PROCESS | 28482 | Values: 162.0 162.0 162.0 166.0 163.0 162.0 162.0 165.0 163.0 164.0 06:00:34 INFO - PROCESS | 28482 | 06:00:34 INFO - PROCESS | 28482 | [#3] audio-fft Cycles:10 Average:82.30 Median:77.00 stddev:11.61 (15.1%) stddev-sans-first:12.09 06:00:34 INFO - PROCESS | 28482 | Values: 76.0 76.0 76.0 108.0 77.0 78.0 77.0 77.0 100.0 78.0 06:00:34 INFO - PROCESS | 28482 | 06:00:34 INFO - PROCESS | 28482 | [#4] audio-oscillator Cycles:10 Average:90.80 Median:84.50 stddev:10.63 (12.6%) stddev-sans-first:10.99 06:00:34 INFO - PROCESS | 28482 | Values: 84.0 85.0 108.0 84.0 84.0 84.0 99.0 85.0 85.0 110.0 06:00:34 INFO - PROCESS | 28482 | 06:00:34 INFO - PROCESS | 28482 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 06:00:34 INFO - PROCESS | 28482 | Values: 113.0 113.0 113.0 113.0 114.0 113.0 112.0 113.0 113.0 113.0 06:00:34 INFO - PROCESS | 28482 | 06:00:34 INFO - PROCESS | 28482 | [#6] imaging-darkroom Cycles:10 Average:148.90 Median:149.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 06:00:34 INFO - PROCESS | 28482 | Values: 149.0 149.0 149.0 150.0 149.0 149.0 149.0 149.0 148.0 148.0 06:00:34 INFO - PROCESS | 28482 | 06:00:34 INFO - PROCESS | 28482 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 06:00:34 INFO - PROCESS | 28482 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 95.0 96.0 97.0 96.0 06:00:34 INFO - PROCESS | 28482 | 06:00:34 INFO - PROCESS | 28482 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:63.00 stddev:0.95 (1.5%) stddev-sans-first:1.00 06:00:34 INFO - PROCESS | 28482 | Values: 63.0 62.0 62.0 61.0 63.0 64.0 64.0 62.0 63.0 63.0 06:00:34 INFO - PROCESS | 28482 | 06:00:34 INFO - PROCESS | 28482 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 06:00:34 INFO - PROCESS | 28482 | Values: 66.0 66.0 66.0 65.0 66.0 66.0 65.0 65.0 66.0 67.0 06:00:34 INFO - PROCESS | 28482 | 06:00:34 INFO - PROCESS | 28482 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:87.50 stddev:1.85 (2.1%) stddev-sans-first:1.94 06:00:34 INFO - PROCESS | 28482 | Values: 87.0 88.0 88.0 93.0 87.0 87.0 87.0 87.0 87.0 88.0 06:00:34 INFO - PROCESS | 28482 | 06:00:34 INFO - PROCESS | 28482 | [#11] stanford-crypto-ccm Cycles:10 Average:146.30 Median:145.50 stddev:15.51 (10.7%) stddev-sans-first:16.23 06:00:34 INFO - PROCESS | 28482 | Values: 139.0 136.0 152.0 156.0 136.0 136.0 134.0 134.0 181.0 159.0 06:00:34 INFO - PROCESS | 28482 | 06:00:34 INFO - PROCESS | 28482 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.80 Median:184.50 stddev:2.10 (1.1%) stddev-sans-first:2.12 06:00:34 INFO - PROCESS | 28482 | Values: 182.0 181.0 186.0 184.0 186.0 182.0 182.0 187.0 185.0 183.0 06:00:34 INFO - PROCESS | 28482 | 06:00:34 INFO - PROCESS | 28482 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:70.00 stddev:1.18 (1.7%) stddev-sans-first:1.12 06:00:34 INFO - PROCESS | 28482 | Values: 71.0 71.0 70.0 69.0 70.0 70.0 69.0 69.0 69.0 67.0 06:00:34 INFO - PROCESS | 28482 | -------- Summary: end -------- 06:00:34 INFO - PROCESS | 28482 | 06:00:36 INFO - TEST-INFO | 28482: exit 0 06:00:36 INFO - TEST-OK | kraken | took 64358ms 06:00:36 INFO - SUITE-END | took 1188s 06:00:36 INFO - Completed test suite (00:19:48) 06:00:36 INFO - javascript benchmark 06:00:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6212.7867684325065, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2585.237354980281, 2615.3853844346586, 2630.51335502932, 2639.445716399556, 2652.3634327761333, 4651.7906694198755, 4714.9528504714945, 4760.0, 4799.1841386964215, 4818.542238487347, 4746.62027037837, 4785.114753770555, 4798.136335459616, 4804.471508134111, 4809.254565542338, 4352.630026447748, 4367.366731823884, 4383.452068491439, 4392.890177745566, 4449.66627502937, 4410.095930334273, 4448.399466072087, 4466.665000124987, 4492.483364413101, 4544.750038747868, 4319.805608747614, 4479.350494178326, 4490.348899409567, 4544.545545445462, 4567.497575266734, 4331.870043898688, 4401.119776044772, 4401.757903315349, 4412.867613971586, 4418.867433976986, 4386.188555117314, 4388.539203383662, 4389.122175564899, 4391.055922976543, 4401.031773009932, 4682.859514214579, 4708.199606066977, 4733.881652958704, 4746.288056791474, 4746.905061898778, 5127.794888204505, 5144.588432925357, 5145.125328694131, 5151.201563757586, 5159.406668233164, 5132.9743351283005, 5137.588992880598, 5147.794088236466, 5147.819826306061, 5184.14461613837, 4758.357621721023, 4805.1590971579835, 4805.567498925114, 4814.133455977888, 4843.5398637129765, 5088.465711100281, 5145.562627176657, 5155.793768249303, 5210.895782084337, 5274.525292723673, 4946.752662366867, 4990.10178167926, 5028.7737051832755, 5032.47159048302, 5096.566791822736, 5124.436312005676, 5135.922961155586, 5137.897242055138, 5138.04946084968, 5138.100832354323, 4905.950940490621, 5097.46476619957, 5102.3877134743325, 5108.719020453911, 5154.5103215194495, 4943.307936888838, 5016.523430274118, 5016.648834581544, 5017.67385119966, 5021.297018417425, 4254.361845723104, 4255.6169944705125, 4256.7658778766845, 4258.829646814154, 4300.376445415397, 3755.6995440364703, 3772.4718539404507, 3784.791836449022, 3798.7150963677836, 3806.657400833938, 4461.709988850715, 4466.843660471868, 4475.194464996267, 4475.619572336323, 4514.706544074625, 5041.722705251246, 5089.694618322913, 5142.871428214325, 5182.663126896739, 5189.195674670432, 4411.316245981878, 4421.358902959053, 4421.977890110529, 4424.867253982386, 4425.915650665544, 4580.335851301543, 4592.0, 4592.127495775792, 4593.242115051046, 4595.586397224266, 4817.78319975595, 4822.445418776885, 4822.734749588806, 4823.131836269506, 4824.179889418737, 5091.745412729275, 5120.641555091257, 5134.922976155286, 5135.229715542699, 5143.0999575073465, 4754.096721622881, 4773.9045219096115, 4793.065352256276, 4805.807767689393, 4846.369971903771, 4975.626827987843, 5026.271190677405, 5066.088104141142, 5067.949320506747, 5072.619553533574, 5031.924521132113, 5088.135017047185, 5093.108705976513, 5098.770555324945, 5136.794528218829, 4780.282957556395, 4836.0, 4935.679180853233, 4939.185034469416, 4939.234418665113, 4870.05034018363, 4933.876653083702, 4971.304017437489, 4989.675671081368, 5042.268871013755, 4903.190973489334, 4926.901461970812, 4930.852074437773, 4931.654784165074, 4991.5258050485145, 5015.824446144367, 5015.899682006267, 5016.147254966738, 5016.573591244785, 5069.518395752398, 4727.527247275245, 4777.761111944319, 4778.665493415428, 4829.517048295143, 4833.564979151894], "value": 4685.001413248238, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.38595606338316, 532.281420082888, 553.3636318234029, 563.9830805075846, 634.3973225435834, 26706.19881403556, 37171.814140929295, 39101.80449097754, 39254.0, 41137.58862411375, 38871.41692874609, 39390.80304598473, 39440.0, 39846.0, 39892.0, 6764.7970560883, 7506.0992680878235, 7687.500312479699, 7695.923040769577, 7714.6142692865415, 6721.697523611425, 9376.81246375072, 9578.042195780436, 9599.232061435068, 9876.95061524695, 9603.0, 9659.32384733069, 9671.854922176173, 9675.419474831497, 9743.561539730728, 9612.567434465465, 9670.323077384586, 9673.129418352382, 9683.12851843334, 9687.854682179774, 9474.668386606505, 9540.666076687283, 9547.474888881108, 9553.379030363005, 9557.426554406758, 35743.106422339646, 35789.0, 35811.0, 35963.0, 36080.45879311812, 3446.913827154316, 3506.842192101336, 3508.6491350864962, 3536.504889315498, 3567.3935430976794, 27197.0, 27746.167614971582, 27833.860830695918, 28016.159515214575, 28497.57253641197, 3288.1615188126875, 3373.510840928076, 3407.727381809449, 3532.7867225189157, 3559.57285125784, 221672.0, 223076.0, 223512.8824355884, 224593.0, 224899.0, 38241.426378604345, 39047.80476097601, 39221.80389098093, 39226.607733922865, 39284.6071539281, 60349.69825150846, 61457.692711536154, 61628.0, 61881.0, 61900.38099618946, 13076.607701768962, 13234.602961911158, 13512.391942362618, 13517.932410337886, 13530.0, 10298.279120461646, 10406.219533535015, 10417.114545263737, 10418.947905260426, 10419.791604167875, 18240.270389184283, 18306.725399119023, 18320.04598505491, 18421.26314947414, 18446.723299150523, 7029.683664235122, 7386.0, 7386.593737344498, 7436.702531898663, 7438.776836694908, 768.9692412303557, 784.2392878907451, 791.1487120138903, 815.48216882279, 818.7380038387778, 24348.391290217533, 24863.378415539755, 26545.336366590604, 27188.39652535513, 27306.180814575197, 922.1101636920404, 928.6656803550717, 937.554661535765, 953.4327075390132, 971.2909576009553, 12132.939335303266, 12974.610761677164, 13025.088243823031, 13148.539801106916, 13168.275744833934, 323529.0, 324983.0, 325299.0, 325671.0, 326340.0, 41678.37482437766, 42045.36931946023, 42061.15877682429, 42063.7896810514, 42120.78939605282, 1127.413744852672, 1178.9882101178878, 1179.2511755035614, 1182.0838849891059, 1182.6511179202157, 21057.473563161042, 21085.89457052705, 21240.78759212388, 21727.782722173208, 21932.232371866907, 37288.44067339046, 40081.0, 41164.794176028925, 41282.793586033076, 45207.0, 313648.0, 315598.0, 316725.0, 317076.0, 317906.0, 26437.86781066159, 27285.454290913673, 31938.36123277475, 32956.1760955978, 33073.669263308024, 35396.646033539335, 35771.82114089413, 35927.28145437024, 36633.0, 36672.266554668226, 29528.557071644373, 29716.702832971394, 30448.84775576108, 31490.0, 32195.678043220207, 30516.0, 30929.381412371178, 31405.0, 31441.213969650027, 31467.527987079753, 29731.256718582208, 30421.69578304277, 30777.230569235948, 30847.537286940264, 31283.06150815479, 26084.0, 28725.42549149047, 28789.856050719613, 28802.567961481014, 29115.272118197216, 22214.006654609817, 22239.888800556542, 22246.332610021724, 22732.545349092594, 22833.31500055001], "value": 16817.430321049327, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.2429836597231, 376.5030160188551, 381.313635456179, 388.67739775985933, 406.4553498312261, 12029.338386388745, 12795.232286062832, 13038.1525200862, 16618.169091545406, 17636.735448968277, 17194.656106877854, 18201.089945502707, 18314.45056648303, 18461.630767384646, 18498.0, 4802.8078876844975, 5094.617903657222, 5095.872603184932, 5098.617603679721, 5102.923456148161, 3403.7277017838637, 3538.7699799512948, 3577.37395955708, 5037.1940489521685, 5088.1095808233595, 3584.551931008624, 5785.421457854223, 5808.912866307009, 5879.353271140171, 5888.32284287306, 5677.290338707661, 5813.128030795372, 5870.559708021894, 5871.324797648283, 5872.970635146797, 5084.51697088776, 5084.898302033976, 5113.054084994251, 5113.565346945514, 5114.923276150861, 21244.256451024296, 21644.675329870064, 21647.45881352963, 21653.566928661498, 21844.672329915065, 1755.3768412213608, 1782.315711652489, 1793.2826869252403, 1806.9096545172688, 1814.2198854492563, 13353.799693004612, 13476.932615336958, 13594.184348938996, 13648.317584120754, 14110.435582576685, 1670.1064930262373, 1681.6636672665516, 1685.7218558937761, 1710.931562737489, 1770.929162833498, 71381.64309178421, 71433.6428317855, 72457.63771181164, 72899.27100729031, 73546.63226683886, 9476.0, 9500.23998080152, 9638.614455421704, 9730.36752611078, 9789.363691360039, 12369.814452783216, 12402.0, 13580.0, 13591.728165436834, 13618.114822536503, 6878.65606719662, 6908.171019477594, 6983.952407138868, 7119.786406407816, 7198.856022879618, 6619.404253617101, 6683.732650694015, 6858.794236172923, 6869.484788640872, 6880.827979300558, 6157.907631385533, 6237.719302631393, 6270.843228919313, 6337.0, 6440.3881631245895, 1753.2373417563401, 1762.8589712822943, 1771.2649250560873, 1804.7834259888898, 1820.7359932809668, 955.6464108279981, 955.9617615295449, 969.0793745941384, 972.9464879431557, 1001.8747656542932, 15690.137042462546, 17271.77677903367, 17999.100044997696, 18058.187381567863, 18651.720224196648, 938.2400255792829, 948.4309414351334, 949.7008442340779, 949.7293271417612, 949.943003419797, 9759.756006099762, 10272.794544109225, 10345.534450949724, 10346.430946298025, 10395.272330936761, 438364.0, 452480.0, 472396.0, 474849.0, 475322.0, 74811.62594186995, 75780.72657820274, 75868.62065689637, 75887.0, 77163.35458067742, 876.268315956171, 886.4238245140581, 889.8709687095335, 893.4237416866085, 896.4755617963565, 14457.27713614336, 15384.0, 15489.845101549292, 15600.687986239984, 15966.521004369422, 36128.0, 36835.815820920725, 37166.814165929, 37431.81284093562, 37750.2449950994, 47082.529174707815, 47384.289235660806, 47647.52352476431, 47711.22887711201, 47886.76056619694, 7266.6729997151315, 7421.814454638461, 7433.851322973402, 7668.079830420385, 8236.217559331853, 12349.814752778535, 12378.690532736753, 12627.74744505123, 12652.367381630698, 12994.480220791062, 10148.188144948534, 10267.0, 10315.742106447398, 10412.895871041193, 10564.26050176511, 9919.305648604528, 10006.899931000597, 10011.949940250252, 10024.649137280161, 10060.849087263843, 10782.191335650015, 10893.346399216072, 10984.231103822656, 11009.559617615205, 11284.379359135326, 7534.924650753423, 7550.207228240955, 7647.235276472308, 7689.269519395649, 8084.9191508086515, 10640.84038739404, 10698.572057117628, 10849.945750271198, 10945.507452164831, 10973.506192221528], "value": 8397.079427697643, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1337.5853485419514, 1338.6051114921097, 1354.329606844612, 1357.3891748713081, 1360.4762166565874, 6872.347127022931, 7250.854982900339, 7254.746083887065, 7255.165655949568, 7267.63661816909, 6754.392104710575, 6970.930290697092, 6998.895016574756, 7111.502194846363, 7123.394511466531, 2027.7769445361034, 2066.1012459580056, 2072.896355182235, 2074.5850829834017, 2075.616011037955, 1744.2586900567208, 2041.4794227471973, 2087.7390326209224, 2093.5603523260133, 2100.002498813059, 1751.6146447781468, 2013.2047841102756, 2064.3394113883564, 2069.4102180878454, 2082.6355387807075, 1976.2490253703645, 1976.5651556657513, 1979.5842872996689, 1980.2178139634907, 1985.0372569303947, 1932.3719791067958, 1955.9315423960236, 2040.6224848403092, 2043.2746375036945, 2043.7751847296786, 6136.846578835521, 6333.050042493617, 7052.188998265163, 7223.963880180618, 7232.240614735482, 4283.336082907154, 4366.650667946588, 4392.604665580081, 4412.514623391425, 4415.0066235097165, 2780.3883145707914, 2844.5733140028956, 2873.6838947715733, 2909.4472050310374, 2909.723576260252, 1057.434272664121, 1060.6287799270272, 1062.8352605346183, 1108.312846035455, 1119.6025410979148, 9511.286653501014, 9538.809223815555, 9619.90380096204, 9628.518574071268, 9651.71044868655, 3543.627919068461, 3704.925901482009, 3706.9073273168387, 3786.2238241160494, 3787.886363409102, 7743.612819359009, 7793.961030194926, 7808.336291415179, 7812.453128281079, 7817.022872140982, 4279.957200428018, 4341.587549182823, 4351.369051487517, 4361.498427680857, 4364.650827933821, 3764.6047165047275, 3884.1843212925037, 3924.0, 3939.1333906540517, 3940.9211815763524, 3090.3664748726906, 3101.9608431175357, 3181.984090079535, 3224.806511609264, 3229.822359770188, 917.9954100229456, 926.3561824531886, 933.09955892564, 939.3236869453982, 947.5688561704408, 737.5500944423898, 737.7491652838061, 753.7550296153772, 760.0271652285084, 789.1792535762858, 4276.144771045803, 4587.9541204588195, 4612.685384665421, 4639.582437580567, 4640.257558790578, 471.6156332588929, 477.77067007836433, 479.966402351839, 484.2663365002025, 484.4840245138916, 3043.269615292314, 3068.6317641883115, 3144.9685503145133, 3168.5722427472456, 3188.0913939527586, 7701.229877012254, 8031.55826429552, 8047.637856296364, 8177.591120443954, 8382.413231073766, 8455.0, 8715.302775777922, 8741.956290218508, 8747.693830716022, 8753.387262891536, 5831.883362332815, 6386.36136386367, 6782.796516104525, 6787.997540725498, 6839.521233513706, 4821.397325334333, 4929.0, 4931.802727890844, 4945.554900059012, 4975.726335051462, 7775.416843736629, 7862.52824830512, 7928.167542407964, 8028.87956680662, 8087.595620219083, 7957.80105497344, 8035.799105022421, 8086.6361013755695, 8107.324582887515, 8111.797205069921, 6145.846353841011, 6329.968350158404, 6415.743370265137, 6416.101745755504, 6427.58220715652, 6717.966410167918, 6725.73097076131, 6792.796216113525, 6804.659767011531, 6804.693788779417, 6060.636361818305, 6170.167027451416, 6202.751889924352, 6206.751729930752, 6335.873282534231, 6111.266648002268, 6153.446189842935, 6191.1641928339095, 6201.348858369806, 6207.0, 5333.12003519415, 5368.033753924331, 5374.086405311028, 5376.059189641719, 5392.892142157213, 4084.3056680365003, 4148.771817550064, 4241.066965267636, 4255.276602977563, 4322.055075216228, 4079.408485769606, 4083.8774836754947, 4135.379693046068, 4247.214265360918, 4305.203537345601], "value": 3713.9440065256426, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.6383454778827, 329.359395974829, 332.92009917619765, 333.10893360261326, 335.2540597171293, 4826.662133650641, 4857.247126695359, 4880.511948805118, 4896.632752543555, 4896.902061958759, 4807.495213002628, 4811.446683631383, 4815.820124069599, 4836.46798852126, 4845.200541910581, 3619.9095022624383, 3750.268697603968, 3751.8311675974646, 3756.8309426075757, 3907.8436862525464, 3771.2457508498273, 3772.849086036555, 3778.886633401002, 3784.924301513968, 3789.450529673196, 3764.171882185915, 3768.3782175941074, 3771.283456143338, 3775.7734535927793, 3777.8666400080006, 3611.4402267648557, 3725.18046029873, 3736.7757934523743, 3752.7185461090526, 3769.8492060317553, 3222.774405791572, 3224.661410551905, 3247.3342964692183, 3254.251522149919, 3274.1323549259464, 4637.744924029177, 4692.301926392304, 4704.776758042934, 4709.529047095263, 4718.197906355894, 4244.639205667522, 4259.0, 4270.765107919063, 4273.166732487166, 4281.186574550857, 4859.927101093486, 5051.646384753069, 5098.770555325019, 5116.8998665287145, 5119.030962042007, 3561.519194908706, 3563.8396272167815, 3591.299696559172, 3596.1549035976786, 3597.6582224688614, 4994.9500504994485, 5236.3978142513715, 5256.684598924076, 5268.972550352682, 5275.788968441258, 3412.1640198151117, 3536.0806190390167, 3541.327147841902, 3602.7117830573493, 3606.945895811565, 3638.599754027055, 3640.8179591020344, 3646.5441819772527, 3647.94528082079, 3652.8904132876055, 4005.0187704012706, 4233.809478573472, 4239.494979282372, 4271.615554600101, 4279.614834664895, 4959.603231741452, 4969.602431805447, 4983.775730092155, 5040.873978150502, 5061.8228362007885, 1210.9333986630643, 1231.8952889004356, 1233.481937586216, 1239.6405042537563, 1241.522014024589, 804.6700852650386, 842.9704960326359, 845.6532821543137, 852.0457088061411, 857.5926434943477, 4307.698461107755, 4326.805293761789, 4331.631811296075, 4335.34969754533, 4340.479142502857, 5068.670536415195, 5086.3133476980875, 5105.540501354896, 5126.9750846999705, 5148.794048238028, 4239.51245606753, 4264.466941632296, 4293.3130699088, 4359.869203923888, 4392.868213953587, 2527.0, 2693.4412308307187, 2694.6362241097595, 2708.390612112259, 2713.905013324524, 4766.928496072562, 4774.188387974053, 4786.7845946932475, 4796.352492413549, 4802.958742534409, 4799.58438236823, 5173.32746742921, 5200.541639809484, 5217.608679349064, 5222.503862133091, 4741.217699079613, 4741.66808323421, 4748.62011039123, 4780.904381912412, 4803.615710743132, 5009.073321435546, 5044.419891712353, 5063.734066483379, 5069.822556210515, 5082.0, 4671.579557839811, 4943.184374578154, 4950.232713929347, 4980.452150263541, 5002.549770520671, 4759.190937540696, 4762.404699412574, 4780.545848144421, 4783.947531543195, 4797.832075877328, 3405.2338223900415, 3479.1650003999366, 3509.649035096572, 3545.9290814184087, 3647.2705458908854, 5136.9743151284, 5201.609879258995, 5270.472952704698, 5278.181881808326, 5283.630145889904, 5092.261622064854, 5107.208382700687, 5118.795248190031, 5121.667091639031, 5143.382794064736, 4808.533397313853, 5023.598112151092, 5054.1660625997765, 5074.82238121664, 5077.136886729191, 4990.950090499048, 4997.675151115166, 5038.571721403721, 5042.29407882904, 5055.5955523557295, 5150.304708864255, 5154.561862241601, 5162.535371816554, 5168.53483186515, 5173.767180476812, 4819.855404337875, 4973.2096445280395, 4999.600031997359, 5012.548870601663, 5018.899622007612], "value": 3771.630423938907, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.9694921655641, 680.0479328939484, 729.0121884846034, 747.9775606731798, 770.3952397368067, 12454.439550220239, 13271.800922986164, 13349.265790381525, 13430.932845335772, 13472.797908031374, 13105.803412948813, 13286.13640113392, 13305.268210248432, 13307.733845323088, 13316.800247996263, 791.1574173505213, 794.9483283586549, 819.4223072733738, 824.1634740738126, 830.8213734047179, 819.5328662662284, 820.2576668115365, 820.2781552234026, 821.1788211788212, 821.4003777242616, 847.9872801907976, 848.4485084694967, 849.1211595998143, 849.3502470609989, 849.3502470609989, 843.9198276163754, 844.995775021121, 845.2139510255429, 845.3786466946789, 845.5898889038812, 622.0513716582203, 622.5735371270649, 623.0125251476416, 623.2769986815273, 623.3672822085587, 13861.09902856311, 14091.0840795349, 14134.15195088298, 14146.504872329419, 14437.855621443756, 11497.0, 12333.383330833512, 12417.130800843854, 12476.937615311956, 12905.741885162339, 10068.848967265498, 10166.339187952759, 10220.795584088351, 10305.484725763681, 10657.52041158142, 3072.2472994116583, 3104.5343198520177, 3106.6641344221975, 3246.724028457584, 3250.203700093469, 57262.42737572654, 57675.711621442046, 58844.0, 58932.705336473475, 59205.303310569354, 9445.66940157105, 9523.952380238194, 9553.61785528571, 9572.664956726481, 10092.4029223727, 16308.592285192728, 16332.346706131859, 16362.181890905407, 16413.0, 16424.0, 6374.139491168726, 6376.0, 6516.478681705452, 6605.30644282353, 6673.0, 3330.58367704037, 3475.669811367916, 3569.839357228931, 3585.6414358563934, 3611.8555257789394, 3014.8191108533556, 3100.55042018906, 3101.1006808025872, 3104.642966058932, 3109.937801244008, 469.6008392866105, 475.08546048855817, 487.34208818095993, 489.7159647404497, 490.15693008026136, 576.9913451298233, 604.1722839709626, 605.2736715940889, 614.6834380294063, 617.7776000639766, 7680.654370553339, 7991.600419978978, 8313.584320783937, 8318.37612179089, 8613.0, 602.8944934636421, 631.2834932351722, 647.6891092275735, 647.821848991531, 652.5953908576618, 3685.3366394048794, 3710.7216958728204, 3835.980820095882, 4002.8398864045694, 4237.406763053175, 50602.746986264836, 60983.475413115026, 61874.381256187764, 64425.0, 65520.171974203295, 52794.0, 55821.0, 55962.0, 56418.0, 57309.14036289459, 50191.74904125456, 50415.0, 50817.49182508201, 52241.21638175506, 52284.47715522872, 8842.0, 9246.630134794803, 9288.959302703346, 9325.813483730422, 9393.154616084585, 32065.198370040936, 33497.83251083729, 33693.49459758057, 34109.0, 35103.171112088654, 40672.593274068066, 41180.58819411768, 42241.78879105585, 43082.138357232056, 44602.3309650362, 10248.231382646181, 10306.845397319195, 10310.484475776331, 10322.225833062701, 10660.147188225079, 12354.567590134302, 12377.443015064524, 12482.438290276777, 12637.97760522127, 12816.359182040675, 10488.370697758159, 10645.467726613793, 10882.836757448804, 11195.832062518906, 11297.378644174649, 11000.22998390137, 11046.33721976684, 11266.0, 11333.093352531943, 11756.94121529387, 11541.653750387502, 11760.941195293968, 11866.050715942876, 11959.521619135137, 12293.062159442075, 9671.806563868824, 9995.639419646315, 10039.49802509857, 10190.1338386238, 10269.435181064815, 9249.260059195383, 9693.616047214047, 9708.320417570701, 9889.930333773986, 9939.044112159007], "value": 6204.455813960605, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1563.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 118.0, 115.0, 116.0, 116.0, 118.0, 116.0, 115.0, 117.0, 116.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 128.0, 126.0, 175.0, 126.0, 126.0, 124.0, 125.0, 167.0, 127.0], "value": 137.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 166.0, 163.0, 162.0, 162.0, 165.0, 163.0, 164.0], "value": 163.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 108.0, 77.0, 78.0, 77.0, 77.0, 100.0, 78.0], "value": 82.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 108.0, 84.0, 84.0, 84.0, 99.0, 85.0, 85.0, 110.0], "value": 90.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 114.0, 113.0, 112.0, 113.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 150.0, 149.0, 149.0, 149.0, 149.0, 148.0, 148.0], "value": 148.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 97.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 61.0, 63.0, 64.0, 64.0, 62.0, 63.0, 63.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 66.0, 66.0, 65.0, 65.0, 66.0, 67.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 88.0, 93.0, 87.0, 87.0, 87.0, 87.0, 87.0, 88.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 136.0, 152.0, 156.0, 136.0, 136.0, 134.0, 134.0, 181.0, 159.0], "value": 146.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 181.0, 186.0, 184.0, 186.0, 182.0, 182.0, 187.0, 185.0, 183.0], "value": 183.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 70.0, 69.0, 70.0, 70.0, 69.0, 69.0, 69.0, 67.0], "value": 69.5, "unit": "score"}]}]} 06:00:36 INFO - Return code: 0 06:00:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:00:36 ERROR - # TBPL SUCCESS # 06:00:36 INFO - Running post-action listener: _resource_record_post_action 06:00:36 INFO - [mozharness: 2017-02-20 14:00:36.797755Z] Finished run-tests step (success) 06:00:36 INFO - Running post-run listener: _resource_record_post_run 06:00:36 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 12288; Write bytes: 360853504; Read time: 364; Write time: 359284 06:00:36 INFO - TinderboxPrint: CPU usage
13.2% 06:00:36 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 364 06:00:36 INFO - TinderboxPrint: I/O write bytes / time
360,853,504 / 359,284 06:00:36 INFO - TinderboxPrint: CPU idle
8,343.9 (86.8%) 06:00:36 INFO - TinderboxPrint: CPU user
1,218.9 (12.7%) 06:00:36 INFO - TinderboxPrint: Swap in / out
0 / 0 06:00:36 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 105254912; Read time: 72; Write time: 132380 06:00:36 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 8192; Write bytes: 255598592; Read time: 292; Write time: 226904 06:00:37 INFO - Running post-run listener: _upload_blobber_files 06:00:37 INFO - Blob upload gear active. 06:00:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:00:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:00:37 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:00:37 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:00:37 INFO - (blobuploader) - INFO - Open directory for files ... 06:00:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:00:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:00:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:38 INFO - (blobuploader) - INFO - Done attempting. 06:00:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:00:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:00:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:39 INFO - (blobuploader) - INFO - Done attempting. 06:00:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:00:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:00:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:40 INFO - (blobuploader) - INFO - Done attempting. 06:00:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:00:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:00:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:00:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:00:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:00:43 INFO - (blobuploader) - INFO - Done attempting. 06:00:43 INFO - (blobuploader) - INFO - Iteration through files over. 06:00:43 INFO - Return code: 0 06:00:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:00:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:00:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa6183c5602c5d666943bb8629251154580b4072bf539742b868e37a9873383f4c7ef4f8451394826789ce9b1834626e4952e0bc4ee9c3aac7607aacb6f18423", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d6d910d47c5455cfc12c7f8493d964034d0b41f6b63a92da71681e7b8bb0056caa30b8b23fc98148c0ef69f3e3d6b2e4b4413571a15a652b3e3eb4da525e0881", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f60809a4e21bda7c742414ea9f1bab8f7b2b79b65b72b86edfe3f9bc459f77050ab078fc4ae856adda6f77612497174113409fe0eb04cbe7c06cc0b3cdca6b4c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/611c61f167ea22f6c06cd97636afaa9187871ecbe5f6ad74a8419cfce4e27b4a365d3e7991c65db162e7aecb6e504c82731d41bf52560b269f1966a846647806"} 06:00:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:00:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:00:43 INFO - Contents: 06:00:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa6183c5602c5d666943bb8629251154580b4072bf539742b868e37a9873383f4c7ef4f8451394826789ce9b1834626e4952e0bc4ee9c3aac7607aacb6f18423", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d6d910d47c5455cfc12c7f8493d964034d0b41f6b63a92da71681e7b8bb0056caa30b8b23fc98148c0ef69f3e3d6b2e4b4413571a15a652b3e3eb4da525e0881", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f60809a4e21bda7c742414ea9f1bab8f7b2b79b65b72b86edfe3f9bc459f77050ab078fc4ae856adda6f77612497174113409fe0eb04cbe7c06cc0b3cdca6b4c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/611c61f167ea22f6c06cd97636afaa9187871ecbe5f6ad74a8419cfce4e27b4a365d3e7991c65db162e7aecb6e504c82731d41bf52560b269f1966a846647806"} 06:00:43 INFO - Running post-run listener: copy_logs_to_upload_dir 06:00:43 INFO - Copying logs to upload dir... 06:00:43 INFO - mkdir: /builds/slave/test/build/upload/logs 06:00:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.876776 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-02-20 06:00:43.117639) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 06:00:43.120613) ========= 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=6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa6183c5602c5d666943bb8629251154580b4072bf539742b868e37a9873383f4c7ef4f8451394826789ce9b1834626e4952e0bc4ee9c3aac7607aacb6f18423", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d6d910d47c5455cfc12c7f8493d964034d0b41f6b63a92da71681e7b8bb0056caa30b8b23fc98148c0ef69f3e3d6b2e4b4413571a15a652b3e3eb4da525e0881", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f60809a4e21bda7c742414ea9f1bab8f7b2b79b65b72b86edfe3f9bc459f77050ab078fc4ae856adda6f77612497174113409fe0eb04cbe7c06cc0b3cdca6b4c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/611c61f167ea22f6c06cd97636afaa9187871ecbe5f6ad74a8419cfce4e27b4a365d3e7991c65db162e7aecb6e504c82731d41bf52560b269f1966a846647806"} build_url:https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011333 build_url: 'https://queue.taskcluster.net/v1/task/bpp91VuURy2KAb9CYu8ZcQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa6183c5602c5d666943bb8629251154580b4072bf539742b868e37a9873383f4c7ef4f8451394826789ce9b1834626e4952e0bc4ee9c3aac7607aacb6f18423", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d6d910d47c5455cfc12c7f8493d964034d0b41f6b63a92da71681e7b8bb0056caa30b8b23fc98148c0ef69f3e3d6b2e4b4413571a15a652b3e3eb4da525e0881", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f60809a4e21bda7c742414ea9f1bab8f7b2b79b65b72b86edfe3f9bc459f77050ab078fc4ae856adda6f77612497174113409fe0eb04cbe7c06cc0b3cdca6b4c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/611c61f167ea22f6c06cd97636afaa9187871ecbe5f6ad74a8419cfce4e27b4a365d3e7991c65db162e7aecb6e504c82731d41bf52560b269f1966a846647806"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-20 06:00:43.144640) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 06:00:43.144931) ========= 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=6d15e502d8e29d5d2676d664000001c3-1487597979.615828-1991985299 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004483 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-20 06:00:43.228939) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 06:00:43.229264) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-20 06:00:43.229570) ========= ========= Total master_lag: 0.16 =========