builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-077 starttime: 1487820358.22 results: success (0) revision: c7b015c488cfb2afbcff295a9639acd85df332f8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:58.222494) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:58.222969) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:58.223285) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:58.239969) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:58.240361) ========= 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=20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-22 19:25:58-- 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% 55.3M=0s 2017-02-22 19:25:58 (55.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.114922 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:58.369942) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:58.370290) ========= 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=20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015017 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:25:58.421594) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-22 19:25:58.421968) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c7b015c488cfb2afbcff295a9639acd85df332f8 --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 c7b015c488cfb2afbcff295a9639acd85df332f8 --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=20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-22 19:25:58,654 truncating revision to first 12 chars 2017-02-22 19:25:58,654 Setting DEBUG logging. 2017-02-22 19:25:58,654 attempt 1/10 2017-02-22 19:25:58,654 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c7b015c488cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-22 19:25:59,966 unpacking tar archive at: mozilla-central-c7b015c488cf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.591993 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-22 19:26:00.027295) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 19:26:00.027647) ========= script_repo_revision: c7b015c488cfb2afbcff295a9639acd85df332f8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 19:26:00.028059) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 19:26:00.028371) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 19:26:00.037874) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-22 19:26:00.038288) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:26:00 INFO - MultiFileLogger online at 20170222 19:26:00 in /builds/slave/test 19:26:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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 19:26:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:26:00 INFO - {'append_to_log': False, 19:26:00 INFO - 'base_work_dir': '/builds/slave/test', 19:26:00 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 19:26:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:26:00 INFO - 'branch': 'Firefox-Non-PGO', 19:26:00 INFO - 'buildbot_json_path': 'buildprops.json', 19:26:00 INFO - 'config_files': ('talos/linux_config.py',), 19:26:00 INFO - 'default_actions': ('clobber', 19:26:00 INFO - 'read-buildbot-config', 19:26:00 INFO - 'download-and-extract', 19:26:00 INFO - 'populate-webroot', 19:26:00 INFO - 'create-virtualenv', 19:26:00 INFO - 'install', 19:26:00 INFO - 'run-tests'), 19:26:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:26:00 INFO - 'download_minidump_stackwalk': True, 19:26:00 INFO - 'download_symbols': 'ondemand', 19:26:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:26:00 INFO - 'tooltool.py': '/tools/tooltool.py', 19:26:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:26:00 INFO - '/tools/misc-python/virtualenv.py')}, 19:26:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:26:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:26:00 INFO - 'gecko_profile': False, 19:26:00 INFO - 'gecko_profile_interval': 0, 19:26:00 INFO - 'installer_path': 'installer.exe', 19:26:00 INFO - 'log_level': 'info', 19:26:00 INFO - 'log_name': 'talos', 19:26:00 INFO - 'log_to_console': True, 19:26:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:26:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:26:00 INFO - 'opt_config_files': (), 19:26:00 INFO - 'pip_index': False, 19:26:00 INFO - 'suite': 'dromaeojs-e10s', 19:26:00 INFO - 'system_bits': '32', 19:26:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:26:00 INFO - 'title': 'talos-linux64-ix-077', 19:26:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:26:00 INFO - 'use_talos_json': True, 19:26:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:26:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:26:00 INFO - 'work_dir': 'build'} 19:26:00 INFO - [mozharness: 2017-02-23 03:26:00.364635Z] Running clobber step. 19:26:00 INFO - Running pre-action listener: _resource_record_pre_action 19:26:00 INFO - Running main action method: clobber 19:26:00 INFO - rmtree: /builds/slave/test/build 19:26:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:26:01 INFO - Running post-action listener: _resource_record_post_action 19:26:01 INFO - [mozharness: 2017-02-23 03:26:01.276311Z] Finished clobber step (success) 19:26:01 INFO - [mozharness: 2017-02-23 03:26:01.276411Z] Running read-buildbot-config step. 19:26:01 INFO - Running pre-action listener: _resource_record_pre_action 19:26:01 INFO - Running main action method: read_buildbot_config 19:26:01 INFO - Using buildbot properties: 19:26:01 INFO - { 19:26:01 INFO - "project": "", 19:26:01 INFO - "product": "firefox", 19:26:01 INFO - "who": "kwierso@gmail.com", 19:26:01 INFO - "installer_path": "public/build/target.tar.bz2", 19:26:01 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 19:26:01 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 19:26:01 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 19:26:01 INFO - "stage_platform": "linux64", 19:26:01 INFO - "basedir": "/builds/slave/test", 19:26:01 INFO - "buildnumber": 433, 19:26:01 INFO - "platform": "ubuntu64_hw", 19:26:01 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:26:01 INFO - "slavebuilddir": "test", 19:26:01 INFO - "taskId": "GrqYadKmTFeg40rrWXdsDg", 19:26:01 INFO - "branch": "mozilla-central", 19:26:01 INFO - "script_repo_revision": "production", 19:26:01 INFO - "revision": "c7b015c488cfb2afbcff295a9639acd85df332f8", 19:26:01 INFO - "slavename": "talos-linux64-ix-077", 19:26:01 INFO - "repo_path": "mozilla-central" 19:26:01 INFO - } 19:26:01 INFO - Finding installer, test and symbols from parent task. 19:26:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrqYadKmTFeg40rrWXdsDg'}, attempt #1 19:26:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GrqYadKmTFeg40rrWXdsDg'}, attempt #1 19:26:02 INFO - Task dependencies: fziZoBLZQf6J64GfmjMy5g 19:26:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g'}, attempt #1 19:26:02 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 19:26:02 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/test_packages.json 19:26:02 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip 19:26:02 INFO - Running post-action listener: _resource_record_post_action 19:26:02 INFO - [mozharness: 2017-02-23 03:26:02.699126Z] Finished read-buildbot-config step (success) 19:26:02 INFO - [mozharness: 2017-02-23 03:26:02.699347Z] Running download-and-extract step. 19:26:02 INFO - Running pre-action listener: _resource_record_pre_action 19:26:02 INFO - Running main action method: download_and_extract 19:26:02 INFO - mkdir: /builds/slave/test/build/tests 19:26:02 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')]} 19:26:02 INFO - trying https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json 19:26:02 INFO - Downloading https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 19:26:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 19:26:03 INFO - Downloaded 896 bytes. 19:26:03 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 19:26:03 INFO - Using the following test package requirements: 19:26:03 INFO - {u'common': [u'target.common.tests.zip'], 19:26:03 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 19:26:03 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 19:26:03 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 19:26:03 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 19:26:03 INFO - u'mozbase': [u'target.common.tests.zip'], 19:26:03 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 19:26:03 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 19:26:03 INFO - u'web-platform': [u'target.common.tests.zip', 19:26:03 INFO - u'target.web-platform.tests.zip'], 19:26:03 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 19:26:03 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 19:26:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip 19:26:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:26:03 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 19:26:06 INFO - Expected file size: 57165889 19:26:06 INFO - Obtained file size: 57165889 19:26:15 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 19:26:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip 19:26:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:26:15 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.common.tests.zip into memory 19:26:16 INFO - Expected file size: 57165889 19:26:16 INFO - Obtained file size: 57165889 19:26:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip 19:26:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 19:26:27 INFO - Fetch https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.talos.tests.zip into memory 19:26:29 INFO - Expected file size: 14404319 19:26:29 INFO - Obtained file size: 14404319 19:26:29 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')]} 19:26:29 INFO - trying https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 19:26:29 INFO - Downloading https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 19:26:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 19:26:32 INFO - Downloaded 61547669 bytes. 19:26:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 19:26:32 INFO - mkdir: /builds/slave/test/properties 19:26:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:26:32 INFO - Writing to file /builds/slave/test/properties/build_url 19:26:32 INFO - Contents: 19:26:32 INFO - build_url:https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 19:26:32 INFO - Running post-action listener: _resource_record_post_action 19:26:32 INFO - Running post-action listener: set_extra_try_arguments 19:26:32 INFO - [mozharness: 2017-02-23 03:26:32.323507Z] Finished download-and-extract step (success) 19:26:32 INFO - [mozharness: 2017-02-23 03:26:32.323733Z] Running populate-webroot step. 19:26:32 INFO - Running pre-action listener: _resource_record_pre_action 19:26:32 INFO - Running main action method: populate_webroot 19:26:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:26:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:26:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:26:32 INFO - '--tppagecycles', 19:26:32 INFO - '7'], 19:26:32 INFO - 'tests': ['tsvgm']}}, 19:26:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:26:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:26:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:26:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:26:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:26:32 INFO - 'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:26:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:26:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:26:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:26:32 INFO - 'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['damp', 'tps']}, 19:26:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:26:32 INFO - 'tests': ['damp', 'tps']}, 19:26:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['dromaeo_dom']}, 19:26:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:26:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['basic_compositor_video']}, 19:26:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:26:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['a11yr', 19:26:32 INFO - 'ts_paint', 19:26:32 INFO - 'tpaint', 19:26:32 INFO - 'sessionrestore', 19:26:32 INFO - 'sessionrestore_no_auto_restore', 19:26:32 INFO - 'tabpaint']}, 19:26:32 INFO - 'other-e10s': {'tests': ['a11yr', 19:26:32 INFO - 'ts_paint', 19:26:32 INFO - 'tpaint', 19:26:32 INFO - 'sessionrestore', 19:26:32 INFO - 'sessionrestore_no_auto_restore', 19:26:32 INFO - 'tabpaint']}, 19:26:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:26:32 INFO - 'ts_paint', 19:26:32 INFO - 'tpaint', 19:26:32 INFO - 'sessionrestore', 19:26:32 INFO - 'sessionrestore_no_auto_restore', 19:26:32 INFO - 'tabpaint']}, 19:26:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:26:32 INFO - 'ts_paint', 19:26:32 INFO - 'tpaint', 19:26:32 INFO - 'sessionrestore', 19:26:32 INFO - 'sessionrestore_no_auto_restore', 19:26:32 INFO - 'tabpaint']}, 19:26:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['a11yr', 19:26:32 INFO - 'ts_paint', 19:26:32 INFO - 'tpaint', 19:26:32 INFO - 'sessionrestore', 19:26:32 INFO - 'sessionrestore_no_auto_restore', 19:26:32 INFO - 'tabpaint']}, 19:26:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['a11yr', 19:26:32 INFO - 'ts_paint', 19:26:32 INFO - 'tpaint', 19:26:32 INFO - 'sessionrestore', 19:26:32 INFO - 'sessionrestore_no_auto_restore', 19:26:32 INFO - 'tabpaint']}, 19:26:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['tsvgx', 19:26:32 INFO - 'tsvgr_opacity', 19:26:32 INFO - 'tart', 19:26:32 INFO - 'tscrollx', 19:26:32 INFO - 'cart', 19:26:32 INFO - 'tsvg_static']}, 19:26:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:26:32 INFO - 'tsvgr_opacity', 19:26:32 INFO - 'tart', 19:26:32 INFO - 'tscrollx', 19:26:32 INFO - 'cart', 19:26:32 INFO - 'tsvg_static']}, 19:26:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:26:32 INFO - 'talos_options': ['--disable-e10s'], 19:26:32 INFO - 'tests': ['tp5o']}, 19:26:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:26:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:26:32 INFO - 'talos_options': ['--disable-e10s', 19:26:32 INFO - '--xperf_path', 19:26:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:26:32 INFO - 'tests': ['tp5n']}, 19:26:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:26:32 INFO - 'talos_options': ['--xperf_path', 19:26:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:26:32 INFO - 'tests': ['tp5n']}}, 19:26:32 INFO - 'talos.zip': {'path': '', 19:26:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:26:32 INFO - Running post-action listener: _resource_record_post_action 19:26:32 INFO - [mozharness: 2017-02-23 03:26:32.339681Z] Finished populate-webroot step (success) 19:26:32 INFO - [mozharness: 2017-02-23 03:26:32.339772Z] Running create-virtualenv step. 19:26:32 INFO - Running pre-action listener: _resource_record_pre_action 19:26:32 INFO - Running main action method: create_virtualenv 19:26:32 INFO - Creating virtualenv /builds/slave/test/build/venv 19:26:32 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 19:26:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:26:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:26:32 INFO - Using real prefix '/usr' 19:26:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:26:32 INFO - Installing distribute.............................................................................................................................................................................................done. 19:26:34 INFO - Installing pip.................done. 19:26:34 INFO - Return code: 0 19:26:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:26:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:26:35 INFO - Reading from file tmpfile_stdout 19:26:35 INFO - Output received: 19:26:35 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) 19:26:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:26:35 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')]} 19:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:35 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')]} 19:26:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:35 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 0x1c6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f09290>, '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 0x1f09b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', '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 19:26:35 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 19:26:35 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 19:26:35 INFO - Using env: {'DISPLAY': ':0', 19:26:35 INFO - 'HOME': '/home/cltbld', 19:26:35 INFO - 'LANG': 'en_US.UTF-8', 19:26:35 INFO - 'LANGUAGE': 'en_US:en', 19:26:35 INFO - 'LOGNAME': 'cltbld', 19:26:35 INFO - 'MAIL': '/var/mail/cltbld', 19:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:35 INFO - 'MOZ_NO_REMOTE': '1', 19:26:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:35 INFO - 'NO_EM_RESTART': '1', 19:26:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:35 INFO - 'PWD': '/builds/slave/test', 19:26:35 INFO - 'SHELL': '/bin/bash', 19:26:35 INFO - 'SHLVL': '1', 19:26:35 INFO - 'TERM': 'linux', 19:26:35 INFO - 'TMOUT': '86400', 19:26:35 INFO - 'USER': 'cltbld', 19:26:35 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', 19:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:35 INFO - '_': '/tools/buildbot/bin/python'} 19:26:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:35 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 19:26:35 INFO - Cleaning up... 19:26:35 INFO - Return code: 0 19:26:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:26:35 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')]} 19:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:35 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')]} 19:26:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:35 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 0x1c6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f09290>, '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 0x1f09b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', '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 19:26:35 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 19:26:35 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 19:26:35 INFO - Using env: {'DISPLAY': ':0', 19:26:35 INFO - 'HOME': '/home/cltbld', 19:26:35 INFO - 'LANG': 'en_US.UTF-8', 19:26:35 INFO - 'LANGUAGE': 'en_US:en', 19:26:35 INFO - 'LOGNAME': 'cltbld', 19:26:35 INFO - 'MAIL': '/var/mail/cltbld', 19:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:35 INFO - 'MOZ_NO_REMOTE': '1', 19:26:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:35 INFO - 'NO_EM_RESTART': '1', 19:26:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:35 INFO - 'PWD': '/builds/slave/test', 19:26:35 INFO - 'SHELL': '/bin/bash', 19:26:35 INFO - 'SHLVL': '1', 19:26:35 INFO - 'TERM': 'linux', 19:26:35 INFO - 'TMOUT': '86400', 19:26:35 INFO - 'USER': 'cltbld', 19:26:35 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', 19:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:35 INFO - '_': '/tools/buildbot/bin/python'} 19:26:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:35 INFO - Downloading/unpacking psutil>=3.1.1 19:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:35 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 19:26:35 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 19:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:26:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:26:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:26:37 INFO - Installing collected packages: psutil 19:26:37 INFO - Running setup.py install for psutil 19:26:37 INFO - building 'psutil._psutil_linux' extension 19:26:37 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 19:26:37 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 19:26:37 INFO - building 'psutil._psutil_posix' extension 19:26:37 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 19:26:38 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 19:26:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:26:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:26:38 INFO - Successfully installed psutil 19:26:38 INFO - Cleaning up... 19:26:38 INFO - Return code: 0 19:26:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:26:38 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')]} 19:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:38 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')]} 19:26:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:38 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 0x1c6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f09290>, '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 0x1f09b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', '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 19:26:38 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 19:26:38 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 19:26:38 INFO - Using env: {'DISPLAY': ':0', 19:26:38 INFO - 'HOME': '/home/cltbld', 19:26:38 INFO - 'LANG': 'en_US.UTF-8', 19:26:38 INFO - 'LANGUAGE': 'en_US:en', 19:26:38 INFO - 'LOGNAME': 'cltbld', 19:26:38 INFO - 'MAIL': '/var/mail/cltbld', 19:26:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:38 INFO - 'MOZ_NO_REMOTE': '1', 19:26:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:38 INFO - 'NO_EM_RESTART': '1', 19:26:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:38 INFO - 'PWD': '/builds/slave/test', 19:26:38 INFO - 'SHELL': '/bin/bash', 19:26:38 INFO - 'SHLVL': '1', 19:26:38 INFO - 'TERM': 'linux', 19:26:38 INFO - 'TMOUT': '86400', 19:26:38 INFO - 'USER': 'cltbld', 19:26:38 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', 19:26:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:38 INFO - '_': '/tools/buildbot/bin/python'} 19:26:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:26:38 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 19:26:38 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 19:26:38 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 19:26:38 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 19:26:38 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 19:26:38 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 19:26:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:26:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:26:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:26:40 INFO - Installing collected packages: mozsystemmonitor 19:26:40 INFO - Running setup.py install for mozsystemmonitor 19:26:40 INFO - Successfully installed mozsystemmonitor 19:26:40 INFO - Cleaning up... 19:26:40 INFO - Return code: 0 19:26:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:26:40 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')]} 19:26:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:40 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')]} 19:26:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:40 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 0x1c6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f09290>, '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 0x1f09b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', '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 19:26:40 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 19:26:40 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 19:26:40 INFO - Using env: {'DISPLAY': ':0', 19:26:40 INFO - 'HOME': '/home/cltbld', 19:26:40 INFO - 'LANG': 'en_US.UTF-8', 19:26:40 INFO - 'LANGUAGE': 'en_US:en', 19:26:40 INFO - 'LOGNAME': 'cltbld', 19:26:40 INFO - 'MAIL': '/var/mail/cltbld', 19:26:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:40 INFO - 'MOZ_NO_REMOTE': '1', 19:26:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:40 INFO - 'NO_EM_RESTART': '1', 19:26:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:40 INFO - 'PWD': '/builds/slave/test', 19:26:40 INFO - 'SHELL': '/bin/bash', 19:26:40 INFO - 'SHLVL': '1', 19:26:40 INFO - 'TERM': 'linux', 19:26:40 INFO - 'TMOUT': '86400', 19:26:40 INFO - 'USER': 'cltbld', 19:26:40 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', 19:26:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:40 INFO - '_': '/tools/buildbot/bin/python'} 19:26:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:40 INFO - Downloading/unpacking jsonschema==2.5.1 19:26:40 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 19:26:40 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 19:26:40 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 19:26:40 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 19:26:40 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 19:26:40 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 19:26:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:26:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:26:42 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 19:26:42 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 19:26:42 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 19:26:42 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 19:26:42 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 19:26:42 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 19:26:43 INFO - Downloading functools32-3.2.3-2.tar.gz 19:26:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:26:43 INFO - warning: no files found matching '*.txt' 19:26:43 INFO - no previously-included directories found matching 'build' 19:26:43 INFO - no previously-included directories found matching 'dist' 19:26:43 INFO - no previously-included directories found matching '.git*' 19:26:43 INFO - Installing collected packages: jsonschema, functools32 19:26:43 INFO - Running setup.py install for functools32 19:26:43 INFO - warning: no files found matching '*.txt' 19:26:43 INFO - no previously-included directories found matching 'build' 19:26:43 INFO - no previously-included directories found matching 'dist' 19:26:43 INFO - no previously-included directories found matching '.git*' 19:26:43 INFO - Successfully installed jsonschema functools32 19:26:43 INFO - Cleaning up... 19:26:43 INFO - Return code: 0 19:26:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:26:43 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')]} 19:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:43 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')]} 19:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x1c6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f09290>, '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 0x1f09b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', '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 19:26:43 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 19:26:43 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 19:26:43 INFO - Using env: {'DISPLAY': ':0', 19:26:43 INFO - 'HOME': '/home/cltbld', 19:26:43 INFO - 'LANG': 'en_US.UTF-8', 19:26:43 INFO - 'LANGUAGE': 'en_US:en', 19:26:43 INFO - 'LOGNAME': 'cltbld', 19:26:43 INFO - 'MAIL': '/var/mail/cltbld', 19:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:43 INFO - 'MOZ_NO_REMOTE': '1', 19:26:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:43 INFO - 'NO_EM_RESTART': '1', 19:26:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:43 INFO - 'PWD': '/builds/slave/test', 19:26:43 INFO - 'SHELL': '/bin/bash', 19:26:43 INFO - 'SHLVL': '1', 19:26:43 INFO - 'TERM': 'linux', 19:26:43 INFO - 'TMOUT': '86400', 19:26:43 INFO - 'USER': 'cltbld', 19:26:43 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', 19:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:43 INFO - '_': '/tools/buildbot/bin/python'} 19:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:26:43 INFO - Cleaning up... 19:26:43 INFO - Return code: 0 19:26:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:26:43 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')]} 19:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:43 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')]} 19:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x1c6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f09290>, '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 0x1f09b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', '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 19:26:43 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 19:26:43 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 19:26:43 INFO - Using env: {'DISPLAY': ':0', 19:26:43 INFO - 'HOME': '/home/cltbld', 19:26:43 INFO - 'LANG': 'en_US.UTF-8', 19:26:43 INFO - 'LANGUAGE': 'en_US:en', 19:26:43 INFO - 'LOGNAME': 'cltbld', 19:26:43 INFO - 'MAIL': '/var/mail/cltbld', 19:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:43 INFO - 'MOZ_NO_REMOTE': '1', 19:26:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:43 INFO - 'NO_EM_RESTART': '1', 19:26:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:43 INFO - 'PWD': '/builds/slave/test', 19:26:43 INFO - 'SHELL': '/bin/bash', 19:26:43 INFO - 'SHLVL': '1', 19:26:43 INFO - 'TERM': 'linux', 19:26:43 INFO - 'TMOUT': '86400', 19:26:43 INFO - 'USER': 'cltbld', 19:26:43 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', 19:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:43 INFO - '_': '/tools/buildbot/bin/python'} 19:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:43 INFO - Downloading/unpacking blobuploader==1.2.4 19:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:43 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 19:26:43 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 19:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:45 INFO - Downloading blobuploader-1.2.4.tar.gz 19:26:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:26:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:26:45 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 19:26:45 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 19:26:45 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 19:26:45 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 19:26:45 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 19:26:45 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 19:26:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:26:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:26:46 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 19:26:46 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 19:26:46 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 19:26:46 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 19:26:46 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 19:26:46 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 19:26:46 INFO - Downloading docopt-0.6.1.tar.gz 19:26:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:26:46 INFO - Installing collected packages: blobuploader, requests, docopt 19:26:46 INFO - Running setup.py install for blobuploader 19:26:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:26:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:26:46 INFO - Running setup.py install for requests 19:26:47 INFO - Running setup.py install for docopt 19:26:47 INFO - Successfully installed blobuploader requests docopt 19:26:47 INFO - Cleaning up... 19:26:47 INFO - Return code: 0 19:26:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26: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')]} 19:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26: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')]} 19:26:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:47 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 0x1c6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f09290>, '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 0x1f09b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', '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 19:26:47 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 19:26:47 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 19:26:47 INFO - Using env: {'DISPLAY': ':0', 19:26:47 INFO - 'HOME': '/home/cltbld', 19:26:47 INFO - 'LANG': 'en_US.UTF-8', 19:26:47 INFO - 'LANGUAGE': 'en_US:en', 19:26:47 INFO - 'LOGNAME': 'cltbld', 19:26:47 INFO - 'MAIL': '/var/mail/cltbld', 19:26:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:47 INFO - 'MOZ_NO_REMOTE': '1', 19:26:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:47 INFO - 'NO_EM_RESTART': '1', 19:26:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:47 INFO - 'PWD': '/builds/slave/test', 19:26:47 INFO - 'SHELL': '/bin/bash', 19:26:47 INFO - 'SHLVL': '1', 19:26:47 INFO - 'TERM': 'linux', 19:26:47 INFO - 'TMOUT': '86400', 19:26:47 INFO - 'USER': 'cltbld', 19:26:47 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', 19:26:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:47 INFO - '_': '/tools/buildbot/bin/python'} 19:26:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:26:47 INFO - Running setup.py (path:/tmp/pip-z_cuwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:26:47 INFO - Running setup.py (path:/tmp/pip-IsS8fc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:26:47 INFO - Running setup.py (path:/tmp/pip-P6kHKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:26:47 INFO - Running setup.py (path:/tmp/pip-Q2CglJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:26:47 INFO - Running setup.py (path:/tmp/pip-TonoHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:26:47 INFO - Running setup.py (path:/tmp/pip-kTj__p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:26:47 INFO - Running setup.py (path:/tmp/pip-w54dgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:26:47 INFO - Running setup.py (path:/tmp/pip-ZukiiH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:26:47 INFO - Running setup.py (path:/tmp/pip-HcIb9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:26:48 INFO - Running setup.py (path:/tmp/pip-eBFAW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:26:48 INFO - Running setup.py (path:/tmp/pip-_RLol7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:26:48 INFO - Running setup.py (path:/tmp/pip-SRiByT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:26:48 INFO - Running setup.py (path:/tmp/pip-i6LJeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:26:48 INFO - Running setup.py (path:/tmp/pip-TVI4Al-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:48 INFO - Running setup.py (path:/tmp/pip-o9SRYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:26:48 INFO - Running setup.py (path:/tmp/pip-EvswEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:26:48 INFO - Running setup.py (path:/tmp/pip-36wLrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:26:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:26:48 INFO - Running setup.py install for manifestparser 19:26:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:26:48 INFO - Running setup.py install for mozcrash 19:26:48 INFO - Running setup.py install for mozdebug 19:26:48 INFO - Running setup.py install for mozdevice 19:26:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for mozfile 19:26:49 INFO - Running setup.py install for mozhttpd 19:26:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for mozinfo 19:26:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for mozInstall 19:26:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for mozleak 19:26:49 INFO - Running setup.py install for mozlog 19:26:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for moznetwork 19:26:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for mozprocess 19:26:49 INFO - Running setup.py install for mozprofile 19:26:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for mozrunner 19:26:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:26:50 INFO - Running setup.py install for mozscreenshot 19:26:50 INFO - Running setup.py install for moztest 19:26:50 INFO - Running setup.py install for mozversion 19:26:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:26:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:26:50 INFO - Cleaning up... 19:26:50 INFO - Return code: 0 19:26:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:50 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')]} 19:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:50 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')]} 19:26:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:50 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 0x1c6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f09290>, '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 0x1f09b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', '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 19:26:50 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 19:26:50 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 19:26:50 INFO - Using env: {'DISPLAY': ':0', 19:26:50 INFO - 'HOME': '/home/cltbld', 19:26:50 INFO - 'LANG': 'en_US.UTF-8', 19:26:50 INFO - 'LANGUAGE': 'en_US:en', 19:26:50 INFO - 'LOGNAME': 'cltbld', 19:26:50 INFO - 'MAIL': '/var/mail/cltbld', 19:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:50 INFO - 'MOZ_NO_REMOTE': '1', 19:26:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:50 INFO - 'NO_EM_RESTART': '1', 19:26:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:50 INFO - 'PWD': '/builds/slave/test', 19:26:50 INFO - 'SHELL': '/bin/bash', 19:26:50 INFO - 'SHLVL': '1', 19:26:50 INFO - 'TERM': 'linux', 19:26:50 INFO - 'TMOUT': '86400', 19:26:50 INFO - 'USER': 'cltbld', 19:26:50 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', 19:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:50 INFO - '_': '/tools/buildbot/bin/python'} 19:26:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:26:50 INFO - Running setup.py (path:/tmp/pip-u0F2T_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:26:50 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)) 19:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:26:50 INFO - Running setup.py (path:/tmp/pip-iq5ue1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:26:50 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)) 19:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:26:50 INFO - Running setup.py (path:/tmp/pip-SaHvc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:26:50 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)) 19:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:26:50 INFO - Running setup.py (path:/tmp/pip-pe1ptj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:26:50 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)) 19:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:26:50 INFO - Running setup.py (path:/tmp/pip-eLwqXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:26:50 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)) 19:26:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:26:50 INFO - Running setup.py (path:/tmp/pip-M0L1Zb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:26:51 INFO - Running setup.py (path:/tmp/pip-EdG6as-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:26:51 INFO - Running setup.py (path:/tmp/pip-Kf9Bnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:26:51 INFO - Running setup.py (path:/tmp/pip-roQGeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:26:51 INFO - Running setup.py (path:/tmp/pip-E3tVFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:26:51 INFO - Running setup.py (path:/tmp/pip-ySvcTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:26:51 INFO - Running setup.py (path:/tmp/pip-O0TonQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:26:51 INFO - Running setup.py (path:/tmp/pip-FHC8YF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:26:51 INFO - Running setup.py (path:/tmp/pip-uGRnIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:51 INFO - Running setup.py (path:/tmp/pip-A0BhLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:26:51 INFO - Running setup.py (path:/tmp/pip-RhvzRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:26:51 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)) 19:26:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:26:51 INFO - Running setup.py (path:/tmp/pip-fRavuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:26:51 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)) 19:26:51 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)) 19:26:51 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)) 19:26:51 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)) 19:26:51 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)) 19:26:51 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)) 19:26:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:26:51 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 19:26:51 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 19:26:51 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 19:26:51 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 19:26:51 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 19:26:51 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 19:26:53 INFO - Downloading blessings-1.6.tar.gz 19:26:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:26:53 INFO - Installing collected packages: blessings 19:26:53 INFO - Running setup.py install for blessings 19:26:54 INFO - Successfully installed blessings 19:26:54 INFO - Cleaning up... 19:26:54 INFO - Return code: 0 19:26:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:26:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:26:54 INFO - Reading from file tmpfile_stdout 19:26:54 INFO - Current package versions: 19:26:54 INFO - argparse == 1.2.1 19:26:54 INFO - blessings == 1.6 19:26:54 INFO - blobuploader == 1.2.4 19:26:54 INFO - docopt == 0.6.1 19:26:54 INFO - functools32 == 3.2.3-2 19:26:54 INFO - jsonschema == 2.5.1 19:26:54 INFO - manifestparser == 1.1 19:26:54 INFO - mozInstall == 1.12 19:26:54 INFO - mozcrash == 1.0 19:26:54 INFO - mozdebug == 0.1 19:26:54 INFO - mozdevice == 0.49 19:26:54 INFO - mozfile == 1.2 19:26:54 INFO - mozhttpd == 0.7 19:26:54 INFO - mozinfo == 0.9 19:26:54 INFO - mozleak == 0.1 19:26:54 INFO - mozlog == 3.4 19:26:54 INFO - moznetwork == 0.27 19:26:54 INFO - mozprocess == 0.25 19:26:54 INFO - mozprofile == 0.28 19:26:54 INFO - mozrunner == 6.13 19:26:54 INFO - mozscreenshot == 0.1 19:26:54 INFO - mozsystemmonitor == 0.3 19:26:54 INFO - moztest == 0.8 19:26:54 INFO - mozversion == 1.4 19:26:54 INFO - psutil == 3.1.1 19:26:54 INFO - requests == 1.2.3 19:26:54 INFO - wsgiref == 0.1.2 19:26:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:54 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')]} 19:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:54 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')]} 19:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:54 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 0x1c6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f09290>, '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 0x1f09b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', '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 19:26:54 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 19:26:54 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 19:26:54 INFO - Using env: {'DISPLAY': ':0', 19:26:54 INFO - 'HOME': '/home/cltbld', 19:26:54 INFO - 'LANG': 'en_US.UTF-8', 19:26:54 INFO - 'LANGUAGE': 'en_US:en', 19:26:54 INFO - 'LOGNAME': 'cltbld', 19:26:54 INFO - 'MAIL': '/var/mail/cltbld', 19:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:54 INFO - 'MOZ_NO_REMOTE': '1', 19:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:54 INFO - 'NO_EM_RESTART': '1', 19:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:54 INFO - 'PWD': '/builds/slave/test', 19:26:54 INFO - 'SHELL': '/bin/bash', 19:26:54 INFO - 'SHLVL': '1', 19:26:54 INFO - 'TERM': 'linux', 19:26:54 INFO - 'TMOUT': '86400', 19:26:54 INFO - 'USER': 'cltbld', 19:26:54 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', 19:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:54 INFO - '_': '/tools/buildbot/bin/python'} 19:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 INFO - Cleaning up... 19:26:54 INFO - Return code: 0 19:26:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:26:54 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')]} 19:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:54 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')]} 19:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:54 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 0x1c6e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d66a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f0bb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f09290>, '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 0x1f09b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a040>, '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': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', '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 19:26:54 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 19:26:54 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 19:26:54 INFO - Using env: {'DISPLAY': ':0', 19:26:54 INFO - 'HOME': '/home/cltbld', 19:26:54 INFO - 'LANG': 'en_US.UTF-8', 19:26:54 INFO - 'LANGUAGE': 'en_US:en', 19:26:54 INFO - 'LOGNAME': 'cltbld', 19:26:54 INFO - 'MAIL': '/var/mail/cltbld', 19:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:54 INFO - 'MOZ_NO_REMOTE': '1', 19:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:26:54 INFO - 'NO_EM_RESTART': '1', 19:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:54 INFO - 'PWD': '/builds/slave/test', 19:26:54 INFO - 'SHELL': '/bin/bash', 19:26:54 INFO - 'SHLVL': '1', 19:26:54 INFO - 'TERM': 'linux', 19:26:54 INFO - 'TMOUT': '86400', 19:26:54 INFO - 'USER': 'cltbld', 19:26:54 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', 19:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:54 INFO - '_': '/tools/buildbot/bin/python'} 19:26:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:26:54 INFO - Cleaning up... 19:26:54 INFO - Return code: 0 19:26:54 INFO - Running post-action listener: _resource_record_post_action 19:26:54 INFO - Running post-action listener: _start_resource_monitoring 19:26:54 INFO - Starting resource monitoring. 19:26:54 INFO - [mozharness: 2017-02-23 03:26:54.807554Z] Finished create-virtualenv step (success) 19:26:54 INFO - [mozharness: 2017-02-23 03:26:54.808072Z] Running install step. 19:26:54 INFO - Running pre-action listener: _resource_record_pre_action 19:26:54 INFO - Running main action method: install 19:26:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:26:55 INFO - Reading from file tmpfile_stdout 19:26:55 INFO - Detecting whether we're running mozinstall >=1.0... 19:26:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:26:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:26:55 INFO - Reading from file tmpfile_stdout 19:26:55 INFO - Output received: 19:26:55 INFO - Usage: mozinstall [options] installer 19:26:55 INFO - Options: 19:26:55 INFO - -h, --help show this help message and exit 19:26:55 INFO - -d DEST, --destination=DEST 19:26:55 INFO - Directory to install application into. [default: 19:26:55 INFO - "/builds/slave/test"] 19:26:55 INFO - --app=APP Application being installed. [default: firefox] 19:26:55 INFO - mkdir: /builds/slave/test/build/application 19:26:55 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'] 19:26:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 19:27:10 INFO - Reading from file tmpfile_stdout 19:27:10 INFO - Output received: 19:27:10 INFO - /builds/slave/test/build/application/firefox/firefox 19:27:10 INFO - Running post-action listener: _resource_record_post_action 19:27:10 INFO - [mozharness: 2017-02-23 03:27:10.520470Z] Finished install step (success) 19:27:10 INFO - [mozharness: 2017-02-23 03:27:10.520744Z] Running run-tests step. 19:27:10 INFO - Running pre-action listener: _resource_record_pre_action 19:27:10 INFO - Running main action method: run_tests 19:27:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:27:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:27:10 INFO - Python 2.7.3 19:27:10 INFO - Return code: 0 19:27:10 INFO - grabbing minidump binary from tooltool 19:27:10 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')]} 19:27:10 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 0x1f09290>, '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 0x1f09b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:27:10 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 19:27:10 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 19:27:10 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 19:27:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:27:10 INFO - Return code: 0 19:27:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:27:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:27:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:27:10 INFO - ENV: RUST_BACKTRACE is now 1 19:27:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:27:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:27:10 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:27:10 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-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-077 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:27:10 INFO - Using env: {'DISPLAY': ':0', 19:27:10 INFO - 'HOME': '/home/cltbld', 19:27:10 INFO - 'LANG': 'en_US.UTF-8', 19:27:10 INFO - 'LANGUAGE': 'en_US:en', 19:27:10 INFO - 'LOGNAME': 'cltbld', 19:27:10 INFO - 'MAIL': '/var/mail/cltbld', 19:27:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:27:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:10 INFO - 'MOZ_NO_REMOTE': '1', 19:27:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:27:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:27:10 INFO - 'NO_EM_RESTART': '1', 19:27:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:10 INFO - 'PWD': '/builds/slave/test', 19:27:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:27:10 INFO - 'RUST_BACKTRACE': '1', 19:27:10 INFO - 'SHELL': '/bin/bash', 19:27:10 INFO - 'SHLVL': '1', 19:27:10 INFO - 'TERM': 'linux', 19:27:10 INFO - 'TMOUT': '86400', 19:27:10 INFO - 'USER': 'cltbld', 19:27:10 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639', 19:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:10 INFO - '_': '/tools/buildbot/bin/python'} 19:27:10 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-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:27:10 INFO - mozversion application_buildid: 20170221181700 19:27:10 INFO - mozversion application_changeset: c7b015c488cfb2afbcff295a9639acd85df332f8 19:27:10 INFO - mozversion application_display_name: Nightly 19:27:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:27:10 INFO - mozversion application_name: Firefox 19:27:10 INFO - mozversion application_remotingname: firefox 19:27:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:27:10 INFO - mozversion application_vendor: Mozilla 19:27:10 INFO - mozversion application_version: 54.0a1 19:27:10 INFO - mozversion platform_buildid: 20170221181700 19:27:10 INFO - mozversion platform_changeset: c7b015c488cfb2afbcff295a9639acd85df332f8 19:27:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:27:10 INFO - mozversion platform_version: 54.0a1 19:27:10 INFO - using testdate: 1487820430 19:27:10 INFO - actual date: 1487820430 19:27:10 INFO - starting webserver on 'localhost:35929' 19:27:10 INFO - SUITE-START | Running 2 tests 19:27:10 INFO - TEST-START | dromaeo_css 19:27:10 INFO - operating with platform_type : linux_ 19:27:10 INFO - Initialising browser for dromaeo_css test... 19:27:10 INFO - TEST-INFO | started process 18273 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjWkaPT/profile http://localhost:35929/getInfo.html) 19:27:13 INFO - PROCESS | 18273 | Iterating over profile /tmp/tmpjWkaPT/profile 19:27:14 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/crashes = Wed Feb 22 2017 19:27:10 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/extensions.ini = Wed Feb 22 2017 19:27:11 GMT-0800 (PST) 19:27:14 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:14 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/prefs.js = Wed Feb 22 2017 19:26:29 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/pluginreg.dat = Wed Feb 22 2017 19:27:13 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/extensions = Wed Feb 22 2017 19:27:11 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/content-prefs.sqlite = Wed Feb 22 2017 19:27:13 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/frequencyCap.json = Wed Feb 22 2017 19:27:13 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/places.sqlite-wal = Wed Feb 22 2017 19:27:15 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/sessionCheckpoints.json = Wed Feb 22 2017 19:27:14 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/permissions.sqlite = Wed Feb 22 2017 19:27:12 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/extensions.json = Wed Feb 22 2017 19:27:13 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/secmod.db = Wed Feb 22 2017 19:27:11 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | __metrics Screen width/height:1600/1200 19:27:15 INFO - PROCESS | 18273 | colorDepth:24 19:27:15 INFO - PROCESS | 18273 | Browser inner width/height: 1024/697 19:27:15 INFO - PROCESS | 18273 | __metrics 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/startupCache = Wed Feb 22 2017 19:27:10 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/safebrowsing = Wed Feb 22 2017 19:27:15 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/addons.json = Wed Feb 22 2017 19:27:13 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | Stat failure 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/blocklist.xml = Wed Feb 22 2017 19:27:11 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/key3.db = Wed Feb 22 2017 19:27:11 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/thumbnails = Wed Feb 22 2017 19:27:13 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/user.js = Wed Feb 22 2017 19:27:10 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/minidumps = Wed Feb 22 2017 19:27:10 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/places.sqlite = Wed Feb 22 2017 19:27:15 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/places.sqlite-shm = Wed Feb 22 2017 19:27:12 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/directoryLinks.json = Wed Feb 22 2017 19:27:13 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/cache2 = Wed Feb 22 2017 19:27:11 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/compatibility.ini = Wed Feb 22 2017 19:27:11 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/cert8.db = Wed Feb 22 2017 19:27:11 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/localstore.rdf = Wed Feb 22 2017 19:26:29 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/gmp = Wed Feb 22 2017 19:27:11 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/bookmarkbackups = Wed Feb 22 2017 19:27:13 GMT-0800 (PST) 19:27:15 INFO - PROCESS | 18273 | No birth date. Using mtime. 19:27:15 INFO - PROCESS | 18273 | Using date: /tmp/tmpjWkaPT/profile/.parentlock = Wed Feb 22 2017 19:27:10 GMT-0800 (PST) 19:27:16 ERROR - PROCESS | 18273 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 19:27:17 INFO - TEST-INFO | 18273: exit 0 19:27:17 INFO - Browser initialized. 19:27:17 INFO - Running cycle 1/1 for dromaeo_css test... 19:27:17 INFO - TEST-INFO | started process 18403 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjWkaPT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:27:29 INFO - PROCESS | 18403 | RSS: Main: 153829376 19:27:29 INFO - PROCESS | 18403 | 19:30:32 INFO - PROCESS | 18403 | Cycle 1(1): loaded http://localhost:35929/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35929/tests/dromaeo/cssquery-ext.html) 19:30:34 INFO - PROCESS | 18403 | RSS: Main: 164904960 19:30:34 INFO - PROCESS | 18403 | 19:32:17 INFO - PROCESS | 18403 | ************************* 19:32:17 INFO - PROCESS | 18403 | A coding exception was thrown and uncaught in a Task. 19:32:17 INFO - PROCESS | 18403 | 19:32:17 ERROR - PROCESS | 18403 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:32:17 INFO - PROCESS | 18403 | Full stack: 19:32:17 INFO - PROCESS | 18403 | ************************* 19:33:36 INFO - PROCESS | 18403 | Cycle 1(1): loaded http://localhost:35929/tests/dromaeo/cssquery-ext.html (next: http://localhost:35929/tests/dromaeo/cssquery-jquery.html) 19:33:37 INFO - PROCESS | 18403 | RSS: Main: 158224384 19:33:37 INFO - PROCESS | 18403 | 19:36:39 INFO - PROCESS | 18403 | Cycle 1(1): loaded http://localhost:35929/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35929/tests/dromaeo/cssquery-mootools.html) 19:36:39 INFO - PROCESS | 18403 | RSS: Main: 166141952 19:36:39 INFO - PROCESS | 18403 | 19:39:42 INFO - PROCESS | 18403 | Cycle 1(1): loaded http://localhost:35929/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35929/tests/dromaeo/cssquery-prototype.html) 19:39:42 INFO - PROCESS | 18403 | RSS: Main: 158494720 19:39:42 INFO - PROCESS | 18403 | 19:42:45 INFO - PROCESS | 18403 | Cycle 1(1): loaded http://localhost:35929/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35929/tests/dromaeo/cssquery-yui.html) 19:42:46 INFO - PROCESS | 18403 | RSS: Main: 166227968 19:42:46 INFO - PROCESS | 18403 | 19:45:49 INFO - PROCESS | 18403 | Cycle 1(1): loaded http://localhost:35929/tests/dromaeo/cssquery-yui.html (next: http://localhost:35929/tests/dromaeo/cssquery-dojo.html) 19:45:49 INFO - PROCESS | 18403 | RSS: Main: 158896128 19:45:49 INFO - PROCESS | 18403 | 19:45:49 INFO - PROCESS | 18403 | __start_tp_report 19:45:49 INFO - PROCESS | 18403 | _x_x_mozilla_page_load 19:45:49 INFO - PROCESS | 18403 | _x_x_mozilla_page_load_details 19:45:49 INFO - PROCESS | 18403 | |i|pagename|runs| 19:45:49 INFO - PROCESS | 18403 | |0;dojo.html;2528.4311030018243;2538.959026799013;2542.65674133992;2559.168270312149;2568.0241508226873;4396.384506169131;4566.748828814414;4585.793639286232;4608.677392582521;4649.256119020957;4437.911241775162;4510.323451482279;4537.8638640840745;4566.58900698937;4592.196365636017;4173.582641735832;4186.476690413699;4221.620054195122;4221.662267018646;4262.1049579588325;4243.554426785174;4251.87244382669;4271.444712187411;4271.807768650403;4312.97843510782;4058.8782336529953;4073.653739432122;4185.330347144442;4267.509236437819;4289.356596510548;4206.621404073648;4230.323148296288;4276.978615106905;4311.439512863339;4314.439122914033;4210.726302790309;4230.963413963568;4245.660347172219;4250.596193361625;4277.657787377034;4390.648748100144;4413.58070983256;4601.953980460186;4602.286645569942;4665.440147182359;4808.591269742076;4867.269909513566;4879.292502587141;4910.410750709902;4963.45402005779;4888.584470320027;4894.290327902434;4895.5975596960125;4920.384951881015;4934.111859865223;4439.66702497314;4591.79336929839;4602.378678878342;4610.354550362952;4652.255639097796;4863.756812159449;4928.852134435972;4938.654294199371;4939.827106051271;5007.749612519432;4651.674382793172;4680.251159814414;4775.283707443911;4791.616670666338;4812.663113582015;4916.115099282092;4917.803287868517;4919.7540122993705;4933.827316043921;4959.900801984012;4698;4895.681780684244;4898.21628539432;4910.263460480956;4943.90112197754;4717.3159891816185;4815.061063092663;4829.299751536078;4849.151398505247;4928.876778080505;4174.749515029108;4190.706650534434;4193.580641935843;4199.97900010498;4230.302000169938;3655.2689462107683;3657.8536858525895;3658.231771328051;3678.3562876496508;3681;4103.179364127127;4131.628153466142;4223.852165174204;4236.08924081324;4242.554531774181;4874.878128046827;4882.609391248691;4906.092372911023;4939.382577177853;4945.282933974553;4224.5353011168745;4241.151769646083;4249.596288352664;4260.893477663083;4271.402003719481;4346.956530434719;4389.363542286351;4404.757738324423;4406.33904914259;4420.690551661417;4608.815647374067;4627.884302892455;4634.165850146871;4672.32251323563;4685.273782563708;4939.160342741814;4951.009798040478;4957.330760347307;4961.82633607822;4991.351124353956;4491.214037543352;4502.932456013097;4531.977340113278;4622.375979242759;4668.673192876466;4743.786529606107;4782.689125206851;4788.449328327287;4824.34871292371;4840.080384726891;4833.830815921426;4839.2983017462975;4841.12859685246;4847.345608342828;4878.048780487771;4610.861674149781;4630.6758526902795;4710.364100846479;4745.9050818984115;4754.168020596451;4755.144074066701;4775.6179505638775;4778.904421911612;4807.134715751198;4824.421069471686;4694.835680751157;4694.859154225379;4701.318308845267;4737.786799594095;4767.69010014348;4598.8620341389815;4614.607758340444;4761.738104404291;4772.737499437564;4820.783064762164;4698.647601429838;4704.012157446974;4711.481737008858;4740.1230772307235;4747.691400058985 19:45:49 INFO - PROCESS | 18403 | |1;ext.html;527.6675694312582;538.5207165622595;550.9063459211935;557.342336043469;583.2767368463107;37946.241075178514;39072.80463597681;39074;42562.14875702484;42775;32970.67029329706;32984.17539561512;33092.834535827285;33155.33689326212;33357.66642333576;7458.5151965122095;7617.238276172394;7617.428692848031;7623.466357354987;9229.123233292827;9127.726168214964;9312.7671808205;9399.201067909202;9399.718008459722;9403.247740180803;9379.624815007392;9405.247580193569;9422.481763503005;9436.245100391987;9447.149756521912;9334.859977100348;9367.6721314754;9431.434113953117;9432.717018489457;9439.008904065076;9179.49512776797;9250.398724082914;9262;9263.907360926372;9289.792326997514;33164;33409.832950835334;33482.162945926306;33483.3303333932;33519;3246.2371342734436;3312.2878581104937;3347.3807345641135;3362.915927101842;3387.813670248135;24831.751682483125;25164.24507264785;25339.366515837253;25628.871855640788;26409;3106.1923899785993;3135.7648176386633;3150.0864749222696;3177.8252196129206;3201.9679803201902;232811;232938;233531;233628;233657;38397.61602383996;39463.80268098641;46475.30287045697;46581.30128048082;46957.29564056542;48181.27728084082;48781.756091219315;48859.267110993365;54264.45735542673;54294;11754.647360579025;11962.88037119635;12124.757504849853;12132.332721700392;12134.089943254292;8704.216620504058;8904.24313933323;8924.553772311489;8931.910680893237;8940.8658870117;15643.687126257411;15724;15732.528024159294;15740.370385184693;15845.445409410615;6528.804135875931;6541.247756507967;6603.636799976048;6714.29499902513;6738.764143254963;718.6011763471323;756.3533179131873;763.4732034895904;764.9961750191325;789.4907784479094;25234.242972710847;26544.336391590365;26690.59784619355;26963.865180673973;27118.5932211017;901.4501154295874;902.8669020379382;913.159892898535;917.8623206518942;946.3958590189562;13774.380152893144;14240.216788076548;14325.283735813167;14543.490977815725;14575.635609109857;312999;314024;316157;316341;318905;49998;50517;50691.49308506868;50698.23952640713;50975.35369694576;1159.8144296912453;1166.3002198680892;1197.634721409961;1197.8203269509643;1205.4213977291124;24388.146414876104;24926.875365623782;26561.203163905113;26670.59994100129;26676;42382.5761742391;42402.363964541175;42766.358504621836;42833;42911;246974;249290;250207;251537.90772553705;252392;22615.92913275626;23213.303600892;23249.418764531023;24071.398215044763;24621.876890615433;35729.46405803863;36076.639233607326;36565;36841.815790920875;36973.63026369702;31055.7391473901;31503.527447087854;31653;31924;31949;30763.846180768953;31036.22409439782;31439.37121257608;31882.202944925633;32466.513002304513;30558.541621875243;30958.22604434907;31604.36791264208;31639.20901977469;31718.207044824063;27008.459830803666;28372.57441138426;28453.288667783472;28508.85745571342;28843.56734649024;21947.7325441896;22087.558248835256;22104.62384005702;22131;22250.109995599996 19:45:49 INFO - PROCESS | 18403 | |2;jquery.html;365.5138665574786;379.78162556530003;401.6626034131329;403.7113463873331;413.1076873952264;16179.676406471863;18508.814911850877;18596.907015464938;18799.530011749714;18896.149673264703;15268.770968435483;15456.768148477782;15801.209939503007;15813.209339533007;16713.9971601704;5129.743512824362;5333.4399888011785;5347.812826551071;5353.384360798519;5355.919661205085;5190.273361729361;5280.1551751719735;5566.888662226753;5609.439056094398;5653.756173641792;5630.774769009235;5636.549076073925;5636.830895073154;5652.265205523277;5652.349979752319;5452.6183167178315;5562.193481945135;5623.634463759883;5625.184348269478;5634.239377684022;5279.366476022863;5485.259489968843;5489.094299440588;5490.368607610096;5503.7798488060425;10901.400422976732;11066;11085.27945683528;11124.888751112467;11151;2442.914497992562;2449.9265022049367;2492.289697436222;2493.7755601995727;2512.0830896722746;8919.331050171199;9041.909580904172;9151.725448236564;9454.527273636291;9760.658376956773;1646.6871294454015;1655.1227849239922;1667.3414001469473;1692.5768557860536;1694.3815507339855;75922.62038689827;78231.60884195543;78931;79521.20478795195;81103.18896811016;10729.570817167227;10751;10898.128149748001;11085.50115244816;11144.387058711687;15444.15057171844;15472.845271547365;15512.92243538775;15534.611634709223;15553.922230389002;6557.180352455943;6827.146606674166;6830.863382732317;7008.369246767714;7024.262452442522;6104.877902441926;6117.781018398333;6290.748370065146;6291.622502649856;6328.398802113792;4594.88512787183;4617.445906491175;4623.214053610895;4800.807967681323;4836.854894353175;1955.5697746495748;1956.8728032678116;1958.657234983872;1963.2207999600996;2015.4558269267216;789.4579454157604;812.5165526511717;820.2084987986502;821.5768879027306;830.7092517618785;17223.311067557406;17671.55821104457;17901;17971.910140449214;18454.26182952694;799.1768478467188;813.7762115418308;817.15832692327;827.9337652987747;837.1753822484848;9456.391875746769;9955.30312878106;10034.799304014025;10041.19670426364;10143;337275;344118;345968;472915;473601;87578;90332;90863;94599.52700236454;97527;982.2682101834037;994.358638678063;1002.0931057393071;1011.039512463163;1018.8166130096506;15991.520254392386;16256.3497460105;16700.081495517865;16854.57863553421;16994.74507882358;38936.610633893295;39381.2123757529;39713.801430993815;40077;40647.18705625812;50105.49894501154;53772.73113634407;54855.725721371135;55201.44798551963;56502.71748641231;8026.799330016796;8112.391570632344;8168.509889406655;8261.339092872677;8458.915410846059;8417.494950302756;11838.763224735629;11909.06098181393;12826.294553799631;12974.221546707227;9970.750731231778;10468.424236667057;10470.581176752845;10528.789424211625;11040.613578524713;10093.596256149673;10185.898141018495;10244.64143754965;10544.26190166681;10694.518746656264;9591.232701383733;9692.46691431978;9858.85211721839;10005.010939725007;10240.231982601485;7638.8090297743;7739.767806965575;7813.648385822538;7924.524528528076;8134;9311.580978855833;9808.460534670661;10038.498075096362;10134.493275336352;10532.684019479428 19:45:49 INFO - PROCESS | 18403 | |3;mootools.html;1357.7216670582532;1377.2425166158614;1387.049870838475;1388.486260083769;1389.5553422904675;7195.100612423447;7535.6232188390495;7688.846223075542;7714.074311082671;7835.255650713188;7825.373970082394;7924.207579242073;7929;7963.044434667848;7975.92024079759;2077.3871707846147;2100.5798840232014;2103.5687684024815;2104.726385569874;2105.368389483149;2083.8853863037525;2130.43543460983;2138.871667699935;2142.8071473567375;2144.5603651251454;2056.3933639576285;2058.969115463269;2067.286786058807;2075.7197778299965;2077.1379877350882;1983.4545499987546;1998.560316730317;2000.249906285143;2001.6597178479694;2004.1482369992768;1720.3462684180056;1763.3387479695114;1980.227711192636;1989.3435166395054;2009.577988622391;7805.063392392893;7839;7849.293563579248;7865.8033549161155;7867.6066196689935;4531.954680453219;4661.044485880387;4678.228092364756;4682.063587282557;4700.717956922562;2770.34896799252;2771.2517620242625;2781.201887138068;2792.2321361625513;2825.9434811303863;1066.477426061232;1066.7333166708322;1072.0512346573262;1074.5218377821873;1117.608836907076;10089.344192627455;10119.24105692076;10160.745981350452;10164.644237451654;10244.078032976993;3837.7505462144873;3995.6603688686205;4053.108316170438;4070.287699652549;4074.266632006267;8212.17878212174;8285.710000149966;8285.917140828635;8307.750767476986;8313.210245026712;4613.746243956548;4614.861554153381;4621.607163391082;4638.698484598558;4643.628509719282;4187.24629566681;4188.455500784939;4294.141171765659;4339.978300108479;4376.584224498668;3280.9336965486314;3309.1892486341;3410.7271418286477;3411.249525104473;3412.5222468853885;942.6465075596651;953.6852838563258;959.0936564946198;959.1032384720309;961.6586111930164;773.9845203095906;798.5488199167452;804.1596531624466;810.6271115287034;812.3825892321878;4837.346958160673;4943.653944223942;5062.11413002723;5098.796048158107;5163.473391592506;510.15059925224085;523.4843678976206;524.6379997801505;526.4209369693307;533.370622665251;3271.3947919634943;3296.653851345622;3324.335132973415;3371.8651253950056;3436;8083.555404452694;8477.915220847712;8754.212120909022;8997.32520060985;9436.952815236018;8932.240759535513;9347.953260233655;9368.578413971387;9372;9378.296627752945;6677.766278180337;6782.1861376635115;6870.106886104774;6874.896876546856;6974.302569743087;4913.796119950635;5071.290019397214;5089.160288552347;5118.334616499831;5286.497782710637;8010.559419231765;8427.83144337122;8549.444286121383;8632.91367086321;8649.178328058824;8716.259117975043;8744.519051452231;8773.692920747742;8775.692850750193;8778;7233.963830180815;7346.081739782528;7384.667689953858;7387.076615423072;7392.815179620553;7215.567065976058;7284.271572842674;7319.707211711472;7327.413806895329;7346.595937223504;6348.238211414659;6564.376384243489;6622.10601568782;6680.465562754871;6708.429783468265;6575.079488871657;6576.243731970693;6610.140681711347;6641.933580664325;6655;5854.531637468907;5948.791792287249;5949.6430214187285;5950.583459157818;5956.523478121827;4540.886477837948;4649.837255696035;4664.277037059262;4712;4739.739314337607;4352.651787857028;4371.235033868997;4444.355568442623;4516.50318464962;4616.792244348945 19:45:49 INFO - PROCESS | 18403 | |4;prototype.html;365.0599705757674;366.8000939487979;369.6396013886461;371.60980969981523;377.88096749523913;4912.6806757560735;4989.950100498994;5015.623828212885;5015.824446144386;5054.624326566517;4907.165781817091;4917.262410638406;4918.631102667306;4953.925691114636;4986.551210391064;3727.4408838674144;3861.764235444659;3878.127421330192;3887.8833634990997;3902.7072969527258;3881.7282790204695;3890.599384221678;3904.824282907262;3913.25648126855;3922.8038598070125;3885.844566217348;3913.0608653922995;3918.6473217410426;3918.902027449308;3922.6273504017217;3664.0580492339104;3891.922161556753;3912.667423269011;3915.8237879295216;3939.684825213976;3378.9324213515592;3384.2554637979606;3407.931841363159;3411.1472131967007;3418.418868792311;4848.612111031109;4884.9267260990755;4917.164082106015;4920.606351491872;4921.163402221595;4398.472183337988;4443;4445.422095127645;4449.399331090293;4450.821967121344;4921.237208232694;5153.175491921312;5217.2695822584865;5231.084560201987;5255.474452554752;3672.6143754905615;3677.5403074615674;3699.5930447650735;3701.2597480503737;3702.5384411783734;5210.713410762407;5335.653182543122;5509.311336082989;5520.806771762969;5540.667559946416;3685.09715119792;3745.9250814983548;3765.6234376562675;3769.208466222124;3779.848806047727;3823.8470461181796;3844.6732027777393;3866.516685414323;3881.0103423626792;3888.9805550972064;4404.383386325917;4413.492448368414;4414.668899832525;4432.933505997413;4436.223660859337;5136.948630513722;5205.167173252337;5231.633785635046;5244;5248.559419869779;1284.916480428769;1297.9740405192033;1304.6803533134444;1305.1907817153426;1305.6017914536158;835.3859912963967;883.6156272021691;887.9644814207488;889.4929889962789;892.1524551675805;4426.18115648606;4439.245328294198;4461.475434406782;4461.754603496839;4469.100632231289;5205.297284866495;5245.26566280721;5269.894602107936;5279.788808447696;5295.895680847297;4170.72890262132;4464.17412778637;4470.240059189946;4510.639148868083;4513.232750432434;2773.8474383908674;2796.4267325198284;2802.467531169071;2811.493931092423;2813.2263627502603;4928.507149285115;4935.777889994959;4944.22008077092;4947.282644016669;4950.752462376867;5197.610179236573;5255.868603284948;5328.973355133277;5375.623706340597;5495.148252020943;5018.34027965179;5027.79888804451;5027.874303142377;5030.320906677552;5034.546890779848;5400.92147475935;5411.83764487066;5420.75606597712;5428.782848686008;5434.75543600531;5186.377634683862;5306.628536002443;5318.57451403879;5350.678959262456;5359.946400536101;4800.423949125988;4923.08922849274;4985.252212168059;5003.3495645565845;5016.297718319365;3747.194353214072;3771.8868433947027;3789.5599672124417;3792.412176112707;3800.7339486236806;5258.579313654975;5347;5372.194170874243;5382.954477298602;5393.757280922289;4913.729744863924;5205.323307969939;5234.476552344735;5256.874547316336;5266.762995665281;5189.080040384931;5241.397239317374;5250.842474725764;5252.107141785982;5284.6524136346525;5183.714895680623;5190.766415511386;5204.843854684365;5205.765740541609;5230.7384630769075;4948.752562371939;5247.344081989751;5264.499872512105;5310.176922577007;5323.1216849219445;5116.692998420107;5126.384833819965;5143.5627971622835;5143.942423030818;5154.252633368215 19:45:49 INFO - PROCESS | 18403 | |5;yui.html;619.043577672496;637.8041172800995;686.3068301015974;704.3942209699658;723.706898706024;12022.759544809098;12849.35753212338;13097.73804523909;13275.7344853103;13380;12854.293013884233;13033.152845065064;13085;13331.800022999663;13343.132696374729;791.2957467853615;820.5609998650723;828.0146625515647;828.6354004238146;828.9875651865227;799.564237490569;818.4802650317032;818.5088946632033;828.6768160417442;835.4611275727152;851.9957400212991;861.5606040919145;861.9224269815716;863.4603372891943;865.7229686500291;855.6662901468401;856.1010938514535;856.1353033436212;856.9657213711444;860.3934226370394;625.2809269340249;625.9718312675896;627.3914303125961;627.6767464755609;628.8207860759661;12913.031522635745;13058.34708264583;13146;13155.408006639724;13619;11157.163212758995;11226.607068752555;11345.37600431976;12834.422450989634;13446.126001809851;11635.88364116365;11776.470058847372;11900.107491938053;11999.760004799942;12142.332171730552;4008.2183974125055;4040.0909795296116;4051.1290072634524;4081.0851095543358;4260.424842646234;40512.9999999997;49113.263301050516;61280.69359653173;61361.07958380628;61948.99999999955;9914.752131196778;10018.849717254247;10057.547410366551;10192.596735293066;10278.691639250834;18046.007469589036;18112.637747244982;18180.63638727218;18300.90849545744;18335.90832045858;6041.939580604226;6108.083787431832;6207.875842483125;6321.620702757849;6334.904976425358;3405.880794172192;3526.770759900598;3546.5921419036627;3547.592026916886;3572.1248294168095;2914.795964282522;2978.136340461242;3013.5329024001317;3024.758019358446;3041.4829478988627;474.28383141456646;476.04077783266746;476.8974670445871;485.114665735035;490.97299648519675;518.7458145508749;532.292051571409;547.5838362844188;549.8872731090197;552.8148070396381;8205.548694821722;8666.696665616673;8804.647814087493;8848.247898928534;8880.911190888008;611.2512172588632;611.911272865432;641.8812519683873;643.2731013954202;646.8318237258346;4090.9795451022555;4148.460700109027;4166.937495937564;4170.124273902515;4274.422952901318;62838;63706.6814665933;63978.6801066001;65421.67289163524;66462;44668.55331446709;48037.519624804;56064.71967640136;57262.14106788402;57549.71225143931;47715.76142119267;50204.49795501998;50875.74562127166;51676.74161629168;54265.45734542683;7840.255175758294;8175.264226219431;8201.384896132933;8239.752807415787;8324.125966773401;35999.28001440009;36629.267414651025;36901.63098368982;37800.43299350457;37830.62169378271;38669.6133038666;39103;42901.356479653456;47057.76471117623;48092.27861582149;11388;11428.25716328436;11579.768404631692;11601.593944211912;11780.587679431179;12264.938675306566;12454.626361209179;12498.687532811753;12728.045396595478;13595.660108497366;12277.447514861675;12524.686882828002;12565.246085234916;12821.807672885101;12970.740585188432;11512.251703639236;11892.702682433008;12003.699907502383;12139.332336721212;12203.938980305042;11211.271267367594;11393.601223957121;11423.428828558704;11475.770484590095;12143.453544590691;8651.91348086511;8908.643654253758;9206.585703643219;9219.222806179467;9621.422714637145;8609.397342185986;9207.76980575491;9231.76920576991;9249.537523123683;9337 19:45:49 INFO - PROCESS | 18403 | __end_tp_report 19:45:49 INFO - PROCESS | 18403 | __start_cc_report 19:45:49 INFO - PROCESS | 18403 | _x_x_mozilla_cycle_collect,396 19:45:49 INFO - PROCESS | 18403 | __end_cc_report 19:45:49 INFO - PROCESS | 18403 | __startTimestamp1487821549405__endTimestamp 19:45:49 INFO - PROCESS | 18403 | 19:45:49 INFO - PROCESS | 18403 | ------- Summary: start ------- 19:45:49 INFO - PROCESS | 18403 | Number of tests: 6 19:45:49 INFO - PROCESS | 18403 | 19:45:49 INFO - PROCESS | 18403 | [#0] dojo.html Cycles:165 Average:4517.21 Median:4634.17 stddev:464.90 (10.0%) stddev-sans-first:439.36 19:45:49 INFO - PROCESS | 18403 | Values: 2528.4 2539.0 2542.7 2559.2 2568.0 4396.4 4566.7 4585.8 4608.7 4649.3 4437.9 4510.3 4537.9 4566.6 4592.2 4173.6 4186.5 4221.6 4221.7 4262.1 4243.6 4251.9 4271.4 4271.8 4313.0 4058.9 4073.7 4185.3 4267.5 4289.4 4206.6 4230.3 4277.0 4311.4 4314.4 4210.7 4231.0 4245.7 4250.6 4277.7 4390.6 4413.6 4602.0 4602.3 4665.4 4808.6 4867.3 4879.3 4910.4 4963.5 4888.6 4894.3 4895.6 4920.4 4934.1 4439.7 4591.8 4602.4 4610.4 4652.3 4863.8 4928.9 4938.7 4939.8 5007.7 4651.7 4680.3 4775.3 4791.6 4812.7 4916.1 4917.8 4919.8 4933.8 4959.9 4698.0 4895.7 4898.2 4910.3 4943.9 4717.3 4815.1 4829.3 4849.2 4928.9 4174.7 4190.7 4193.6 4200.0 4230.3 3655.3 3657.9 3658.2 3678.4 3681.0 4103.2 4131.6 4223.9 4236.1 4242.6 4874.9 4882.6 4906.1 4939.4 4945.3 4224.5 4241.2 4249.6 4260.9 4271.4 4347.0 4389.4 4404.8 4406.3 4420.7 4608.8 4627.9 4634.2 4672.3 4685.3 4939.2 4951.0 4957.3 4961.8 4991.4 4491.2 4502.9 4532.0 4622.4 4668.7 4743.8 4782.7 4788.4 4824.3 4840.1 4833.8 4839.3 4841.1 4847.3 4878.0 4610.9 4630.7 4710.4 4745.9 4754.2 4755.1 4775.6 4778.9 4807.1 4824.4 4694.8 4694.9 4701.3 4737.8 4767.7 4598.9 4614.6 4761.7 4772.7 4820.8 4698.6 4704.0 4711.5 4740.1 4747.7 19:45:49 INFO - PROCESS | 18403 | 19:45:49 INFO - PROCESS | 18403 | [#1] ext.html Cycles:180 Average:41359.13 Median:33159.67 stddev:70335.70 (212.1%) stddev-sans-first:70466.20 19:45:49 INFO - PROCESS | 18403 | Values: 527.7 538.5 550.9 557.3 583.3 37946.2 39072.8 39074.0 42562.1 42775.0 32970.7 32984.2 33092.8 33155.3 33357.7 7458.5 7617.2 7617.4 7623.5 9229.1 9127.7 9312.8 9399.2 9399.7 9403.2 9379.6 9405.2 9422.5 9436.2 9447.1 9334.9 9367.7 9431.4 9432.7 9439.0 9179.5 9250.4 9262.0 9263.9 9289.8 33164.0 33409.8 33482.2 33483.3 33519.0 3246.2 3312.3 3347.4 3362.9 3387.8 24831.8 25164.2 25339.4 25628.9 26409.0 3106.2 3135.8 3150.1 3177.8 3202.0 232811.0 232938.0 233531.0 233628.0 233657.0 38397.6 39463.8 46475.3 46581.3 46957.3 48181.3 48781.8 48859.3 54264.5 54294.0 11754.6 11962.9 12124.8 12132.3 12134.1 8704.2 8904.2 8924.6 8931.9 8940.9 15643.7 15724.0 15732.5 15740.4 15845.4 6528.8 6541.2 6603.6 6714.3 6738.8 718.6 756.4 763.5 765.0 789.5 25234.2 26544.3 26690.6 26963.9 27118.6 901.5 902.9 913.2 917.9 946.4 13774.4 14240.2 14325.3 14543.5 14575.6 312999.0 314024.0 316157.0 316341.0 318905.0 49998.0 50517.0 50691.5 50698.2 50975.4 1159.8 1166.3 1197.6 1197.8 1205.4 24388.1 24926.9 26561.2 26670.6 26676.0 42382.6 42402.4 42766.4 42833.0 42911.0 246974.0 249290.0 250207.0 251537.9 252392.0 22615.9 23213.3 23249.4 24071.4 24621.9 35729.5 36076.6 36565.0 36841.8 36973.6 31055.7 31503.5 31653.0 31924.0 31949.0 30763.8 31036.2 31439.4 31882.2 32466.5 30558.5 30958.2 31604.4 31639.2 31718.2 27008.5 28372.6 28453.3 28508.9 28843.6 21947.7 22087.6 22104.6 22131.0 22250.1 19:45:49 INFO - PROCESS | 18403 | 19:45:49 INFO - PROCESS | 18403 | [#2] jquery.html Cycles:180 Average:25203.43 Median:473258.00 stddev:66667.34 (14.1%) stddev-sans-first:66828.28 19:45:49 INFO - PROCESS | 18403 | Values: 365.5 379.8 401.7 403.7 413.1 16179.7 18508.8 18596.9 18799.5 18896.1 15268.8 15456.8 15801.2 15813.2 16714.0 5129.7 5333.4 5347.8 5353.4 5355.9 5190.3 5280.2 5566.9 5609.4 5653.8 5630.8 5636.5 5636.8 5652.3 5652.3 5452.6 5562.2 5623.6 5625.2 5634.2 5279.4 5485.3 5489.1 5490.4 5503.8 10901.4 11066.0 11085.3 11124.9 11151.0 2442.9 2449.9 2492.3 2493.8 2512.1 8919.3 9041.9 9151.7 9454.5 9760.7 1646.7 1655.1 1667.3 1692.6 1694.4 75922.6 78231.6 78931.0 79521.2 81103.2 10729.6 10751.0 10898.1 11085.5 11144.4 15444.2 15472.8 15512.9 15534.6 15553.9 6557.2 6827.1 6830.9 7008.4 7024.3 6104.9 6117.8 6290.7 6291.6 6328.4 4594.9 4617.4 4623.2 4800.8 4836.9 1955.6 1956.9 1958.7 1963.2 2015.5 789.5 812.5 820.2 821.6 830.7 17223.3 17671.6 17901.0 17971.9 18454.3 799.2 813.8 817.2 827.9 837.2 9456.4 9955.3 10034.8 10041.2 10143.0 337275.0 344118.0 345968.0 472915.0 473601.0 87578.0 90332.0 90863.0 94599.5 97527.0 982.3 994.4 1002.1 1011.0 1018.8 15991.5 16256.3 16700.1 16854.6 16994.7 38936.6 39381.2 39713.8 40077.0 40647.2 50105.5 53772.7 54855.7 55201.4 56502.7 8026.8 8112.4 8168.5 8261.3 8458.9 8417.5 11838.8 11909.1 12826.3 12974.2 9970.8 10468.4 10470.6 10528.8 11040.6 10093.6 10185.9 10244.6 10544.3 10694.5 9591.2 9692.5 9858.9 10005.0 10240.2 7638.8 7739.8 7813.6 7924.5 8134.0 9311.6 9808.5 10038.5 10134.5 10532.7 19:45:49 INFO - PROCESS | 18403 | 19:45:49 INFO - PROCESS | 18403 | [#3] mootools.html Cycles:180 Average:4968.53 Median:5093.98 stddev:2790.10 (54.8%) stddev-sans-first:2784.73 19:45:49 INFO - PROCESS | 18403 | Values: 1357.7 1377.2 1387.0 1388.5 1389.6 7195.1 7535.6 7688.8 7714.1 7835.3 7825.4 7924.2 7929.0 7963.0 7975.9 2077.4 2100.6 2103.6 2104.7 2105.4 2083.9 2130.4 2138.9 2142.8 2144.6 2056.4 2059.0 2067.3 2075.7 2077.1 1983.5 1998.6 2000.2 2001.7 2004.1 1720.3 1763.3 1980.2 1989.3 2009.6 7805.1 7839.0 7849.3 7865.8 7867.6 4532.0 4661.0 4678.2 4682.1 4700.7 2770.3 2771.3 2781.2 2792.2 2825.9 1066.5 1066.7 1072.1 1074.5 1117.6 10089.3 10119.2 10160.7 10164.6 10244.1 3837.8 3995.7 4053.1 4070.3 4074.3 8212.2 8285.7 8285.9 8307.8 8313.2 4613.7 4614.9 4621.6 4638.7 4643.6 4187.2 4188.5 4294.1 4340.0 4376.6 3280.9 3309.2 3410.7 3411.2 3412.5 942.6 953.7 959.1 959.1 961.7 774.0 798.5 804.2 810.6 812.4 4837.3 4943.7 5062.1 5098.8 5163.5 510.2 523.5 524.6 526.4 533.4 3271.4 3296.7 3324.3 3371.9 3436.0 8083.6 8477.9 8754.2 8997.3 9437.0 8932.2 9348.0 9368.6 9372.0 9378.3 6677.8 6782.2 6870.1 6874.9 6974.3 4913.8 5071.3 5089.2 5118.3 5286.5 8010.6 8427.8 8549.4 8632.9 8649.2 8716.3 8744.5 8773.7 8775.7 8778.0 7234.0 7346.1 7384.7 7387.1 7392.8 7215.6 7284.3 7319.7 7327.4 7346.6 6348.2 6564.4 6622.1 6680.5 6708.4 6575.1 6576.2 6610.1 6641.9 6655.0 5854.5 5948.8 5949.6 5950.6 5956.5 4540.9 4649.8 4664.3 4712.0 4739.7 4352.7 4371.2 4444.4 4516.5 4616.8 19:45:49 INFO - PROCESS | 18403 | 19:45:49 INFO - PROCESS | 18403 | [#4] prototype.html Cycles:180 Average:4313.25 Median:4921.20 stddev:1251.35 (25.4%) stddev-sans-first:1219.26 19:45:49 INFO - PROCESS | 18403 | Values: 365.1 366.8 369.6 371.6 377.9 4912.7 4990.0 5015.6 5015.8 5054.6 4907.2 4917.3 4918.6 4953.9 4986.6 3727.4 3861.8 3878.1 3887.9 3902.7 3881.7 3890.6 3904.8 3913.3 3922.8 3885.8 3913.1 3918.6 3918.9 3922.6 3664.1 3891.9 3912.7 3915.8 3939.7 3378.9 3384.3 3407.9 3411.1 3418.4 4848.6 4884.9 4917.2 4920.6 4921.2 4398.5 4443.0 4445.4 4449.4 4450.8 4921.2 5153.2 5217.3 5231.1 5255.5 3672.6 3677.5 3699.6 3701.3 3702.5 5210.7 5335.7 5509.3 5520.8 5540.7 3685.1 3745.9 3765.6 3769.2 3779.8 3823.8 3844.7 3866.5 3881.0 3889.0 4404.4 4413.5 4414.7 4432.9 4436.2 5136.9 5205.2 5231.6 5244.0 5248.6 1284.9 1298.0 1304.7 1305.2 1305.6 835.4 883.6 888.0 889.5 892.2 4426.2 4439.2 4461.5 4461.8 4469.1 5205.3 5245.3 5269.9 5279.8 5295.9 4170.7 4464.2 4470.2 4510.6 4513.2 2773.8 2796.4 2802.5 2811.5 2813.2 4928.5 4935.8 4944.2 4947.3 4950.8 5197.6 5255.9 5329.0 5375.6 5495.1 5018.3 5027.8 5027.9 5030.3 5034.5 5400.9 5411.8 5420.8 5428.8 5434.8 5186.4 5306.6 5318.6 5350.7 5359.9 4800.4 4923.1 4985.3 5003.3 5016.3 3747.2 3771.9 3789.6 3792.4 3800.7 5258.6 5347.0 5372.2 5383.0 5393.8 4913.7 5205.3 5234.5 5256.9 5266.8 5189.1 5241.4 5250.8 5252.1 5284.7 5183.7 5190.8 5204.8 5205.8 5230.7 4948.8 5247.3 5264.5 5310.2 5323.1 5116.7 5126.4 5143.6 5143.9 5154.3 19:45:49 INFO - PROCESS | 18403 | 19:45:49 INFO - PROCESS | 18403 | [#5] yui.html Cycles:180 Average:14370.78 Median:475.16 stddev:17397.62 (3661.4%) stddev-sans-first:17415.78 19:45:49 INFO - PROCESS | 18403 | Values: 619.0 637.8 686.3 704.4 723.7 12022.8 12849.4 13097.7 13275.7 13380.0 12854.3 13033.2 13085.0 13331.8 13343.1 791.3 820.6 828.0 828.6 829.0 799.6 818.5 818.5 828.7 835.5 852.0 861.6 861.9 863.5 865.7 855.7 856.1 856.1 857.0 860.4 625.3 626.0 627.4 627.7 628.8 12913.0 13058.3 13146.0 13155.4 13619.0 11157.2 11226.6 11345.4 12834.4 13446.1 11635.9 11776.5 11900.1 11999.8 12142.3 4008.2 4040.1 4051.1 4081.1 4260.4 40513.0 49113.3 61280.7 61361.1 61949.0 9914.8 10018.8 10057.5 10192.6 10278.7 18046.0 18112.6 18180.6 18300.9 18335.9 6041.9 6108.1 6207.9 6321.6 6334.9 3405.9 3526.8 3546.6 3547.6 3572.1 2914.8 2978.1 3013.5 3024.8 3041.5 474.3 476.0 476.9 485.1 491.0 518.7 532.3 547.6 549.9 552.8 8205.5 8666.7 8804.6 8848.2 8880.9 611.3 611.9 641.9 643.3 646.8 4091.0 4148.5 4166.9 4170.1 4274.4 62838.0 63706.7 63978.7 65421.7 66462.0 44668.6 48037.5 56064.7 57262.1 57549.7 47715.8 50204.5 50875.7 51676.7 54265.5 7840.3 8175.3 8201.4 8239.8 8324.1 35999.3 36629.3 36901.6 37800.4 37830.6 38669.6 39103.0 42901.4 47057.8 48092.3 11388.0 11428.3 11579.8 11601.6 11780.6 12264.9 12454.6 12498.7 12728.0 13595.7 12277.4 12524.7 12565.2 12821.8 12970.7 11512.3 11892.7 12003.7 12139.3 12203.9 11211.3 11393.6 11423.4 11475.8 12143.5 8651.9 8908.6 9206.6 9219.2 9621.4 8609.4 9207.8 9231.8 9249.5 9337.0 19:45:49 INFO - PROCESS | 18403 | -------- Summary: end -------- 19:45:49 INFO - PROCESS | 18403 | 19:45:50 INFO - TEST-INFO | 18403: exit 0 19:45:50 INFO - TEST-OK | dromaeo_css | took 1119467ms 19:45:50 INFO - TEST-START | kraken 19:45:50 INFO - operating with platform_type : linux_ 19:45:50 INFO - Initialising browser for kraken test... 19:45:50 INFO - TEST-INFO | started process 18775 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1gj5rF/profile http://localhost:35929/getInfo.html) 19:45:53 INFO - PROCESS | 18775 | Iterating over profile /tmp/tmp1gj5rF/profile 19:45:53 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:53 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/crashes = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/webappsstore.sqlite = Wed Feb 22 2017 19:45:54 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/extensions.ini = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/mimeTypes.rdf = Wed Feb 22 2017 19:45:53 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/prefs.js = Wed Feb 22 2017 19:26:29 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/pluginreg.dat = Wed Feb 22 2017 19:45:52 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/extensions = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/content-prefs.sqlite = Wed Feb 22 2017 19:45:52 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | __metrics Screen width/height:1600/1200 19:45:54 INFO - PROCESS | 18775 | colorDepth:24 19:45:54 INFO - PROCESS | 18775 | Browser inner width/height: 1024/697 19:45:54 INFO - PROCESS | 18775 | __metrics 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/frequencyCap.json = Wed Feb 22 2017 19:45:52 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/places.sqlite-wal = Wed Feb 22 2017 19:45:54 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/sessionCheckpoints.json = Wed Feb 22 2017 19:45:53 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/permissions.sqlite = Wed Feb 22 2017 19:45:51 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/extensions.json = Wed Feb 22 2017 19:45:52 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/secmod.db = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/storage.sqlite = Wed Feb 22 2017 19:45:54 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/startupCache = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/safebrowsing = Wed Feb 22 2017 19:45:54 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/addons.json = Wed Feb 22 2017 19:45:52 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | Stat failure 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/blocklist.xml = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/key3.db = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/thumbnails = Wed Feb 22 2017 19:45:52 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/user.js = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/minidumps = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/places.sqlite = Wed Feb 22 2017 19:45:54 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/places.sqlite-shm = Wed Feb 22 2017 19:45:51 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/directoryLinks.json = Wed Feb 22 2017 19:45:52 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/cache2 = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/compatibility.ini = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/cert8.db = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/localstore.rdf = Wed Feb 22 2017 19:26:29 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/gmp = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/bookmarkbackups = Wed Feb 22 2017 19:45:52 GMT-0800 (PST) 19:45:54 INFO - PROCESS | 18775 | Stat failure 19:45:54 INFO - PROCESS | 18775 | Stat failure 19:45:54 INFO - PROCESS | 18775 | No birth date. Using mtime. 19:45:54 INFO - PROCESS | 18775 | Using date: /tmp/tmp1gj5rF/profile/.parentlock = Wed Feb 22 2017 19:45:50 GMT-0800 (PST) 19:45:55 ERROR - PROCESS | 18775 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 19:45:56 INFO - TEST-INFO | 18775: exit 0 19:45:56 INFO - Browser initialized. 19:45:56 INFO - Running cycle 1/1 for kraken test... 19:45:56 INFO - TEST-INFO | started process 18903 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1gj5rF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:46:08 INFO - PROCESS | 18903 | RSS: Main: 153849856 19:46:08 INFO - PROCESS | 18903 | 19:46:11 INFO - PROCESS | 18903 | Cycle 1(1): loaded http://localhost:35929/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35929/tests/kraken/driver.html?testName=audio-beat-detection) 19:46:12 INFO - PROCESS | 18903 | RSS: Main: 160333824 19:46:12 INFO - PROCESS | 18903 | 19:46:15 INFO - PROCESS | 18903 | Cycle 1(1): loaded http://localhost:35929/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35929/tests/kraken/driver.html?testName=audio-dft) 19:46:15 INFO - PROCESS | 18903 | RSS: Main: 159350784 19:46:15 INFO - PROCESS | 18903 | 19:46:18 INFO - PROCESS | 18903 | Cycle 1(1): loaded http://localhost:35929/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35929/tests/kraken/driver.html?testName=audio-fft) 19:46:19 INFO - PROCESS | 18903 | RSS: Main: 155906048 19:46:19 INFO - PROCESS | 18903 | 19:46:21 INFO - PROCESS | 18903 | Cycle 1(1): loaded http://localhost:35929/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35929/tests/kraken/driver.html?testName=audio-oscillator) 19:46:22 INFO - PROCESS | 18903 | RSS: Main: 156315648 19:46:22 INFO - PROCESS | 18903 | 19:46:24 INFO - PROCESS | 18903 | Cycle 1(1): loaded http://localhost:35929/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35929/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:46:25 INFO - PROCESS | 18903 | RSS: Main: 156676096 19:46:25 INFO - PROCESS | 18903 | 19:46:28 INFO - PROCESS | 18903 | Cycle 1(1): loaded http://localhost:35929/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35929/tests/kraken/driver.html?testName=imaging-darkroom) 19:46:28 INFO - PROCESS | 18903 | RSS: Main: 161361920 19:46:28 INFO - PROCESS | 18903 | 19:46:32 INFO - PROCESS | 18903 | Cycle 1(1): loaded http://localhost:35929/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35929/tests/kraken/driver.html?testName=imaging-desaturate) 19:46:32 INFO - PROCESS | 18903 | RSS: Main: 158412800 19:46:32 INFO - PROCESS | 18903 | 19:46:35 INFO - PROCESS | 18903 | Cycle 1(1): loaded http://localhost:35929/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35929/tests/kraken/driver.html?testName=json-parse-financial) 19:46:36 INFO - PROCESS | 18903 | RSS: Main: 159244288 19:46:36 INFO - PROCESS | 18903 | 19:46:38 INFO - PROCESS | 18903 | Cycle 1(1): loaded http://localhost:35929/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35929/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:46:38 INFO - PROCESS | 18903 | RSS: Main: 159662080 19:46:38 INFO - PROCESS | 18903 | 19:46:40 INFO - PROCESS | 18903 | Cycle 1(1): loaded http://localhost:35929/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35929/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:46:41 INFO - PROCESS | 18903 | RSS: Main: 159674368 19:46:41 INFO - PROCESS | 18903 | 19:46:43 INFO - PROCESS | 18903 | Cycle 1(1): loaded http://localhost:35929/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35929/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:46:44 INFO - PROCESS | 18903 | RSS: Main: 160018432 19:46:44 INFO - PROCESS | 18903 | 19:46:47 INFO - PROCESS | 18903 | Cycle 1(1): loaded http://localhost:35929/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35929/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:46:47 INFO - PROCESS | 18903 | RSS: Main: 160604160 19:46:47 INFO - PROCESS | 18903 | 19:46:51 INFO - PROCESS | 18903 | Cycle 1(1): loaded http://localhost:35929/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35929/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:46:51 INFO - PROCESS | 18903 | RSS: Main: 160342016 19:46:51 INFO - PROCESS | 18903 | 19:46:53 INFO - PROCESS | 18903 | Cycle 1(1): loaded http://localhost:35929/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35929/tests/kraken/driver.html?testName=ai-astar) 19:46:53 INFO - PROCESS | 18903 | RSS: Main: 161935360 19:46:53 INFO - PROCESS | 18903 | 19:46:53 INFO - PROCESS | 18903 | __start_tp_report 19:46:53 INFO - PROCESS | 18903 | _x_x_mozilla_page_load 19:46:53 INFO - PROCESS | 18903 | _x_x_mozilla_page_load_details 19:46:53 INFO - PROCESS | 18903 | |i|pagename|runs| 19:46:53 INFO - PROCESS | 18903 | |0;ai-astar;107;116;115;115;113;114;116;116;114;114 19:46:53 INFO - PROCESS | 18903 | |1;audio-beat-detection;151;125;126;166;126;127;129;130;158;130 19:46:53 INFO - PROCESS | 18903 | |2;audio-dft;164;167;166;167;176;164;164;164;164;164 19:46:53 INFO - PROCESS | 18903 | |3;audio-fft;77;78;77;118;78;78;78;77;103;79 19:46:53 INFO - PROCESS | 18903 | |4;audio-oscillator;85;84;106;85;83;85;99;84;85;113 19:46:53 INFO - PROCESS | 18903 | |5;imaging-gaussian-blur;114;114;113;114;113;119;114;114;114;114 19:46:53 INFO - PROCESS | 18903 | |6;imaging-darkroom;151;150;151;150;150;150;149;152;150;152 19:46:53 INFO - PROCESS | 18903 | |7;imaging-desaturate;96;97;96;96;96;96;96;96;97;96 19:46:53 INFO - PROCESS | 18903 | |8;json-parse-financial;65;65;65;65;67;66;65;66;66;66 19:46:53 INFO - PROCESS | 18903 | |9;json-stringify-tinderbox;66;67;66;65;66;66;66;66;67;66 19:46:53 INFO - PROCESS | 18903 | |10;stanford-crypto-aes;92;93;88;89;87;86;94;86;89;86 19:46:53 INFO - PROCESS | 18903 | |11;stanford-crypto-ccm;142;164;136;137;150;145;140;136;192;141 19:46:53 INFO - PROCESS | 18903 | |12;stanford-crypto-pbkdf2;183;185;191;182;185;186;185;192;183;180 19:46:53 INFO - PROCESS | 18903 | |13;stanford-crypto-sha256-iterative;71;75;71;72;71;72;71;70;69;68 19:46:53 INFO - PROCESS | 18903 | __end_tp_report 19:46:53 INFO - PROCESS | 18903 | __start_cc_report 19:46:53 INFO - PROCESS | 18903 | _x_x_mozilla_cycle_collect,879 19:46:53 INFO - PROCESS | 18903 | __end_cc_report 19:46:53 INFO - PROCESS | 18903 | __startTimestamp1487821613723__endTimestamp 19:46:53 INFO - PROCESS | 18903 | 19:46:53 INFO - PROCESS | 18903 | ------- Summary: start ------- 19:46:53 INFO - PROCESS | 18903 | Number of tests: 14 19:46:53 INFO - PROCESS | 18903 | 19:46:53 INFO - PROCESS | 18903 | [#0] ai-astar Cycles:10 Average:114.00 Median:115.00 stddev:2.67 (2.3%) stddev-sans-first:1.09 19:46:53 INFO - PROCESS | 18903 | Values: 107.0 116.0 115.0 115.0 113.0 114.0 116.0 116.0 114.0 114.0 19:46:53 INFO - PROCESS | 18903 | 19:46:53 INFO - PROCESS | 18903 | [#1] audio-beat-detection Cycles:10 Average:136.80 Median:130.00 stddev:15.37 (11.8%) stddev-sans-first:15.42 19:46:53 INFO - PROCESS | 18903 | Values: 151.0 125.0 126.0 166.0 126.0 127.0 129.0 130.0 158.0 130.0 19:46:53 INFO - PROCESS | 18903 | 19:46:53 INFO - PROCESS | 18903 | [#2] audio-dft Cycles:10 Average:166.00 Median:165.00 stddev:3.74 (2.3%) stddev-sans-first:3.90 19:46:53 INFO - PROCESS | 18903 | Values: 164.0 167.0 166.0 167.0 176.0 164.0 164.0 164.0 164.0 164.0 19:46:53 INFO - PROCESS | 18903 | 19:46:53 INFO - PROCESS | 18903 | [#3] audio-fft Cycles:10 Average:84.30 Median:78.00 stddev:14.27 (18.3%) stddev-sans-first:14.89 19:46:53 INFO - PROCESS | 18903 | Values: 77.0 78.0 77.0 118.0 78.0 78.0 78.0 77.0 103.0 79.0 19:46:53 INFO - PROCESS | 18903 | 19:46:53 INFO - PROCESS | 18903 | [#4] audio-oscillator Cycles:10 Average:90.90 Median:85.00 stddev:10.95 (12.9%) stddev-sans-first:11.40 19:46:53 INFO - PROCESS | 18903 | Values: 85.0 84.0 106.0 85.0 83.0 85.0 99.0 84.0 85.0 113.0 19:46:53 INFO - PROCESS | 18903 | 19:46:53 INFO - PROCESS | 18903 | [#5] imaging-gaussian-blur Cycles:10 Average:114.30 Median:114.00 stddev:1.70 (1.5%) stddev-sans-first:1.80 19:46:53 INFO - PROCESS | 18903 | Values: 114.0 114.0 113.0 114.0 113.0 119.0 114.0 114.0 114.0 114.0 19:46:53 INFO - PROCESS | 18903 | 19:46:53 INFO - PROCESS | 18903 | [#6] imaging-darkroom Cycles:10 Average:150.50 Median:150.50 stddev:0.97 (0.6%) stddev-sans-first:1.01 19:46:53 INFO - PROCESS | 18903 | Values: 151.0 150.0 151.0 150.0 150.0 150.0 149.0 152.0 150.0 152.0 19:46:53 INFO - PROCESS | 18903 | 19:46:53 INFO - PROCESS | 18903 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 19:46:53 INFO - PROCESS | 18903 | Values: 96.0 97.0 96.0 96.0 96.0 96.0 96.0 96.0 97.0 96.0 19:46:53 INFO - PROCESS | 18903 | 19:46:53 INFO - PROCESS | 18903 | [#8] json-parse-financial Cycles:10 Average:65.60 Median:66.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 19:46:53 INFO - PROCESS | 18903 | Values: 65.0 65.0 65.0 65.0 67.0 66.0 65.0 66.0 66.0 66.0 19:46:53 INFO - PROCESS | 18903 | 19:46:53 INFO - PROCESS | 18903 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 19:46:53 INFO - PROCESS | 18903 | Values: 66.0 67.0 66.0 65.0 66.0 66.0 66.0 66.0 67.0 66.0 19:46:53 INFO - PROCESS | 18903 | 19:46:53 INFO - PROCESS | 18903 | [#10] stanford-crypto-aes Cycles:10 Average:89.00 Median:89.00 stddev:3.02 (3.4%) stddev-sans-first:3.00 19:46:53 INFO - PROCESS | 18903 | Values: 92.0 93.0 88.0 89.0 87.0 86.0 94.0 86.0 89.0 86.0 19:46:53 INFO - PROCESS | 18903 | 19:46:53 INFO - PROCESS | 18903 | [#11] stanford-crypto-ccm Cycles:10 Average:148.30 Median:143.50 stddev:17.52 (12.2%) stddev-sans-first:18.43 19:46:53 INFO - PROCESS | 18903 | Values: 142.0 164.0 136.0 137.0 150.0 145.0 140.0 136.0 192.0 141.0 19:46:53 INFO - PROCESS | 18903 | 19:46:53 INFO - PROCESS | 18903 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.20 Median:185.00 stddev:3.77 (2.0%) stddev-sans-first:3.91 19:46:53 INFO - PROCESS | 18903 | Values: 183.0 185.0 191.0 182.0 185.0 186.0 185.0 192.0 183.0 180.0 19:46:53 INFO - PROCESS | 18903 | 19:46:53 INFO - PROCESS | 18903 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:71.00 stddev:1.89 (2.7%) stddev-sans-first:2.00 19:46:53 INFO - PROCESS | 18903 | Values: 71.0 75.0 71.0 72.0 71.0 72.0 71.0 70.0 69.0 68.0 19:46:53 INFO - PROCESS | 18903 | -------- Summary: end -------- 19:46:53 INFO - PROCESS | 18903 | 19:46:54 INFO - TEST-INFO | 18903: exit 0 19:46:54 INFO - TEST-OK | kraken | took 64653ms 19:46:54 INFO - SUITE-END | took 1184s 19:46:55 INFO - Completed test suite (00:19:44) 19:46:55 INFO - javascript benchmark 19:46:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6253.818481721322, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2528.4311030018243, 2538.959026799013, 2542.65674133992, 2559.168270312149, 2568.0241508226873, 4396.384506169131, 4566.748828814414, 4585.793639286232, 4608.677392582521, 4649.256119020957, 4437.911241775162, 4510.323451482279, 4537.8638640840745, 4566.58900698937, 4592.196365636017, 4173.582641735832, 4186.476690413699, 4221.620054195122, 4221.662267018646, 4262.1049579588325, 4243.554426785174, 4251.87244382669, 4271.444712187411, 4271.807768650403, 4312.97843510782, 4058.8782336529953, 4073.653739432122, 4185.330347144442, 4267.509236437819, 4289.356596510548, 4206.621404073648, 4230.323148296288, 4276.978615106905, 4311.439512863339, 4314.439122914033, 4210.726302790309, 4230.963413963568, 4245.660347172219, 4250.596193361625, 4277.657787377034, 4390.648748100144, 4413.58070983256, 4601.953980460186, 4602.286645569942, 4665.440147182359, 4808.591269742076, 4867.269909513566, 4879.292502587141, 4910.410750709902, 4963.45402005779, 4888.584470320027, 4894.290327902434, 4895.5975596960125, 4920.384951881015, 4934.111859865223, 4439.66702497314, 4591.79336929839, 4602.378678878342, 4610.354550362952, 4652.255639097796, 4863.756812159449, 4928.852134435972, 4938.654294199371, 4939.827106051271, 5007.749612519432, 4651.674382793172, 4680.251159814414, 4775.283707443911, 4791.616670666338, 4812.663113582015, 4916.115099282092, 4917.803287868517, 4919.7540122993705, 4933.827316043921, 4959.900801984012, 4698.0, 4895.681780684244, 4898.21628539432, 4910.263460480956, 4943.90112197754, 4717.3159891816185, 4815.061063092663, 4829.299751536078, 4849.151398505247, 4928.876778080505, 4174.749515029108, 4190.706650534434, 4193.580641935843, 4199.97900010498, 4230.302000169938, 3655.2689462107683, 3657.8536858525895, 3658.231771328051, 3678.3562876496508, 3681.0, 4103.179364127127, 4131.628153466142, 4223.852165174204, 4236.08924081324, 4242.554531774181, 4874.878128046827, 4882.609391248691, 4906.092372911023, 4939.382577177853, 4945.282933974553, 4224.5353011168745, 4241.151769646083, 4249.596288352664, 4260.893477663083, 4271.402003719481, 4346.956530434719, 4389.363542286351, 4404.757738324423, 4406.33904914259, 4420.690551661417, 4608.815647374067, 4627.884302892455, 4634.165850146871, 4672.32251323563, 4685.273782563708, 4939.160342741814, 4951.009798040478, 4957.330760347307, 4961.82633607822, 4991.351124353956, 4491.214037543352, 4502.932456013097, 4531.977340113278, 4622.375979242759, 4668.673192876466, 4743.786529606107, 4782.689125206851, 4788.449328327287, 4824.34871292371, 4840.080384726891, 4833.830815921426, 4839.2983017462975, 4841.12859685246, 4847.345608342828, 4878.048780487771, 4610.861674149781, 4630.6758526902795, 4710.364100846479, 4745.9050818984115, 4754.168020596451, 4755.144074066701, 4775.6179505638775, 4778.904421911612, 4807.134715751198, 4824.421069471686, 4694.835680751157, 4694.859154225379, 4701.318308845267, 4737.786799594095, 4767.69010014348, 4598.8620341389815, 4614.607758340444, 4761.738104404291, 4772.737499437564, 4820.783064762164, 4698.647601429838, 4704.012157446974, 4711.481737008858, 4740.1230772307235, 4747.691400058985], "value": 4487.93941532014, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [527.6675694312582, 538.5207165622595, 550.9063459211935, 557.342336043469, 583.2767368463107, 37946.241075178514, 39072.80463597681, 39074.0, 42562.14875702484, 42775.0, 32970.67029329706, 32984.17539561512, 33092.834535827285, 33155.33689326212, 33357.66642333576, 7458.5151965122095, 7617.238276172394, 7617.428692848031, 7623.466357354987, 9229.123233292827, 9127.726168214964, 9312.7671808205, 9399.201067909202, 9399.718008459722, 9403.247740180803, 9379.624815007392, 9405.247580193569, 9422.481763503005, 9436.245100391987, 9447.149756521912, 9334.859977100348, 9367.6721314754, 9431.434113953117, 9432.717018489457, 9439.008904065076, 9179.49512776797, 9250.398724082914, 9262.0, 9263.907360926372, 9289.792326997514, 33164.0, 33409.832950835334, 33482.162945926306, 33483.3303333932, 33519.0, 3246.2371342734436, 3312.2878581104937, 3347.3807345641135, 3362.915927101842, 3387.813670248135, 24831.751682483125, 25164.24507264785, 25339.366515837253, 25628.871855640788, 26409.0, 3106.1923899785993, 3135.7648176386633, 3150.0864749222696, 3177.8252196129206, 3201.9679803201902, 232811.0, 232938.0, 233531.0, 233628.0, 233657.0, 38397.61602383996, 39463.80268098641, 46475.30287045697, 46581.30128048082, 46957.29564056542, 48181.27728084082, 48781.756091219315, 48859.267110993365, 54264.45735542673, 54294.0, 11754.647360579025, 11962.88037119635, 12124.757504849853, 12132.332721700392, 12134.089943254292, 8704.216620504058, 8904.24313933323, 8924.553772311489, 8931.910680893237, 8940.8658870117, 15643.687126257411, 15724.0, 15732.528024159294, 15740.370385184693, 15845.445409410615, 6528.804135875931, 6541.247756507967, 6603.636799976048, 6714.29499902513, 6738.764143254963, 718.6011763471323, 756.3533179131873, 763.4732034895904, 764.9961750191325, 789.4907784479094, 25234.242972710847, 26544.336391590365, 26690.59784619355, 26963.865180673973, 27118.5932211017, 901.4501154295874, 902.8669020379382, 913.159892898535, 917.8623206518942, 946.3958590189562, 13774.380152893144, 14240.216788076548, 14325.283735813167, 14543.490977815725, 14575.635609109857, 312999.0, 314024.0, 316157.0, 316341.0, 318905.0, 49998.0, 50517.0, 50691.49308506868, 50698.23952640713, 50975.35369694576, 1159.8144296912453, 1166.3002198680892, 1197.634721409961, 1197.8203269509643, 1205.4213977291124, 24388.146414876104, 24926.875365623782, 26561.203163905113, 26670.59994100129, 26676.0, 42382.5761742391, 42402.363964541175, 42766.358504621836, 42833.0, 42911.0, 246974.0, 249290.0, 250207.0, 251537.90772553705, 252392.0, 22615.92913275626, 23213.303600892, 23249.418764531023, 24071.398215044763, 24621.876890615433, 35729.46405803863, 36076.639233607326, 36565.0, 36841.815790920875, 36973.63026369702, 31055.7391473901, 31503.527447087854, 31653.0, 31924.0, 31949.0, 30763.846180768953, 31036.22409439782, 31439.37121257608, 31882.202944925633, 32466.513002304513, 30558.541621875243, 30958.22604434907, 31604.36791264208, 31639.20901977469, 31718.207044824063, 27008.459830803666, 28372.57441138426, 28453.288667783472, 28508.85745571342, 28843.56734649024, 21947.7325441896, 22087.558248835256, 22104.62384005702, 22131.0, 22250.109995599996], "value": 16387.936717819943, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.5138665574786, 379.78162556530003, 401.6626034131329, 403.7113463873331, 413.1076873952264, 16179.676406471863, 18508.814911850877, 18596.907015464938, 18799.530011749714, 18896.149673264703, 15268.770968435483, 15456.768148477782, 15801.209939503007, 15813.209339533007, 16713.9971601704, 5129.743512824362, 5333.4399888011785, 5347.812826551071, 5353.384360798519, 5355.919661205085, 5190.273361729361, 5280.1551751719735, 5566.888662226753, 5609.439056094398, 5653.756173641792, 5630.774769009235, 5636.549076073925, 5636.830895073154, 5652.265205523277, 5652.349979752319, 5452.6183167178315, 5562.193481945135, 5623.634463759883, 5625.184348269478, 5634.239377684022, 5279.366476022863, 5485.259489968843, 5489.094299440588, 5490.368607610096, 5503.7798488060425, 10901.400422976732, 11066.0, 11085.27945683528, 11124.888751112467, 11151.0, 2442.914497992562, 2449.9265022049367, 2492.289697436222, 2493.7755601995727, 2512.0830896722746, 8919.331050171199, 9041.909580904172, 9151.725448236564, 9454.527273636291, 9760.658376956773, 1646.6871294454015, 1655.1227849239922, 1667.3414001469473, 1692.5768557860536, 1694.3815507339855, 75922.62038689827, 78231.60884195543, 78931.0, 79521.20478795195, 81103.18896811016, 10729.570817167227, 10751.0, 10898.128149748001, 11085.50115244816, 11144.387058711687, 15444.15057171844, 15472.845271547365, 15512.92243538775, 15534.611634709223, 15553.922230389002, 6557.180352455943, 6827.146606674166, 6830.863382732317, 7008.369246767714, 7024.262452442522, 6104.877902441926, 6117.781018398333, 6290.748370065146, 6291.622502649856, 6328.398802113792, 4594.88512787183, 4617.445906491175, 4623.214053610895, 4800.807967681323, 4836.854894353175, 1955.5697746495748, 1956.8728032678116, 1958.657234983872, 1963.2207999600996, 2015.4558269267216, 789.4579454157604, 812.5165526511717, 820.2084987986502, 821.5768879027306, 830.7092517618785, 17223.311067557406, 17671.55821104457, 17901.0, 17971.910140449214, 18454.26182952694, 799.1768478467188, 813.7762115418308, 817.15832692327, 827.9337652987747, 837.1753822484848, 9456.391875746769, 9955.30312878106, 10034.799304014025, 10041.19670426364, 10143.0, 337275.0, 344118.0, 345968.0, 472915.0, 473601.0, 87578.0, 90332.0, 90863.0, 94599.52700236454, 97527.0, 982.2682101834037, 994.358638678063, 1002.0931057393071, 1011.039512463163, 1018.8166130096506, 15991.520254392386, 16256.3497460105, 16700.081495517865, 16854.57863553421, 16994.74507882358, 38936.610633893295, 39381.2123757529, 39713.801430993815, 40077.0, 40647.18705625812, 50105.49894501154, 53772.73113634407, 54855.725721371135, 55201.44798551963, 56502.71748641231, 8026.799330016796, 8112.391570632344, 8168.509889406655, 8261.339092872677, 8458.915410846059, 8417.494950302756, 11838.763224735629, 11909.06098181393, 12826.294553799631, 12974.221546707227, 9970.750731231778, 10468.424236667057, 10470.581176752845, 10528.789424211625, 11040.613578524713, 10093.596256149673, 10185.898141018495, 10244.64143754965, 10544.26190166681, 10694.518746656264, 9591.232701383733, 9692.46691431978, 9858.85211721839, 10005.010939725007, 10240.231982601485, 7638.8090297743, 7739.767806965575, 7813.648385822538, 7924.524528528076, 8134.0, 9311.580978855833, 9808.460534670661, 10038.498075096362, 10134.493275336352, 10532.684019479428], "value": 8354.499222360191, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.7216670582532, 1377.2425166158614, 1387.049870838475, 1388.486260083769, 1389.5553422904675, 7195.100612423447, 7535.6232188390495, 7688.846223075542, 7714.074311082671, 7835.255650713188, 7825.373970082394, 7924.207579242073, 7929.0, 7963.044434667848, 7975.92024079759, 2077.3871707846147, 2100.5798840232014, 2103.5687684024815, 2104.726385569874, 2105.368389483149, 2083.8853863037525, 2130.43543460983, 2138.871667699935, 2142.8071473567375, 2144.5603651251454, 2056.3933639576285, 2058.969115463269, 2067.286786058807, 2075.7197778299965, 2077.1379877350882, 1983.4545499987546, 1998.560316730317, 2000.249906285143, 2001.6597178479694, 2004.1482369992768, 1720.3462684180056, 1763.3387479695114, 1980.227711192636, 1989.3435166395054, 2009.577988622391, 7805.063392392893, 7839.0, 7849.293563579248, 7865.8033549161155, 7867.6066196689935, 4531.954680453219, 4661.044485880387, 4678.228092364756, 4682.063587282557, 4700.717956922562, 2770.34896799252, 2771.2517620242625, 2781.201887138068, 2792.2321361625513, 2825.9434811303863, 1066.477426061232, 1066.7333166708322, 1072.0512346573262, 1074.5218377821873, 1117.608836907076, 10089.344192627455, 10119.24105692076, 10160.745981350452, 10164.644237451654, 10244.078032976993, 3837.7505462144873, 3995.6603688686205, 4053.108316170438, 4070.287699652549, 4074.266632006267, 8212.17878212174, 8285.710000149966, 8285.917140828635, 8307.750767476986, 8313.210245026712, 4613.746243956548, 4614.861554153381, 4621.607163391082, 4638.698484598558, 4643.628509719282, 4187.24629566681, 4188.455500784939, 4294.141171765659, 4339.978300108479, 4376.584224498668, 3280.9336965486314, 3309.1892486341, 3410.7271418286477, 3411.249525104473, 3412.5222468853885, 942.6465075596651, 953.6852838563258, 959.0936564946198, 959.1032384720309, 961.6586111930164, 773.9845203095906, 798.5488199167452, 804.1596531624466, 810.6271115287034, 812.3825892321878, 4837.346958160673, 4943.653944223942, 5062.11413002723, 5098.796048158107, 5163.473391592506, 510.15059925224085, 523.4843678976206, 524.6379997801505, 526.4209369693307, 533.370622665251, 3271.3947919634943, 3296.653851345622, 3324.335132973415, 3371.8651253950056, 3436.0, 8083.555404452694, 8477.915220847712, 8754.212120909022, 8997.32520060985, 9436.952815236018, 8932.240759535513, 9347.953260233655, 9368.578413971387, 9372.0, 9378.296627752945, 6677.766278180337, 6782.1861376635115, 6870.106886104774, 6874.896876546856, 6974.302569743087, 4913.796119950635, 5071.290019397214, 5089.160288552347, 5118.334616499831, 5286.497782710637, 8010.559419231765, 8427.83144337122, 8549.444286121383, 8632.91367086321, 8649.178328058824, 8716.259117975043, 8744.519051452231, 8773.692920747742, 8775.692850750193, 8778.0, 7233.963830180815, 7346.081739782528, 7384.667689953858, 7387.076615423072, 7392.815179620553, 7215.567065976058, 7284.271572842674, 7319.707211711472, 7327.413806895329, 7346.595937223504, 6348.238211414659, 6564.376384243489, 6622.10601568782, 6680.465562754871, 6708.429783468265, 6575.079488871657, 6576.243731970693, 6610.140681711347, 6641.933580664325, 6655.0, 5854.531637468907, 5948.791792287249, 5949.6430214187285, 5950.583459157818, 5956.523478121827, 4540.886477837948, 4649.837255696035, 4664.277037059262, 4712.0, 4739.739314337607, 4352.651787857028, 4371.235033868997, 4444.355568442623, 4516.50318464962, 4616.792244348945], "value": 3946.198915001868, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.0599705757674, 366.8000939487979, 369.6396013886461, 371.60980969981523, 377.88096749523913, 4912.6806757560735, 4989.950100498994, 5015.623828212885, 5015.824446144386, 5054.624326566517, 4907.165781817091, 4917.262410638406, 4918.631102667306, 4953.925691114636, 4986.551210391064, 3727.4408838674144, 3861.764235444659, 3878.127421330192, 3887.8833634990997, 3902.7072969527258, 3881.7282790204695, 3890.599384221678, 3904.824282907262, 3913.25648126855, 3922.8038598070125, 3885.844566217348, 3913.0608653922995, 3918.6473217410426, 3918.902027449308, 3922.6273504017217, 3664.0580492339104, 3891.922161556753, 3912.667423269011, 3915.8237879295216, 3939.684825213976, 3378.9324213515592, 3384.2554637979606, 3407.931841363159, 3411.1472131967007, 3418.418868792311, 4848.612111031109, 4884.9267260990755, 4917.164082106015, 4920.606351491872, 4921.163402221595, 4398.472183337988, 4443.0, 4445.422095127645, 4449.399331090293, 4450.821967121344, 4921.237208232694, 5153.175491921312, 5217.2695822584865, 5231.084560201987, 5255.474452554752, 3672.6143754905615, 3677.5403074615674, 3699.5930447650735, 3701.2597480503737, 3702.5384411783734, 5210.713410762407, 5335.653182543122, 5509.311336082989, 5520.806771762969, 5540.667559946416, 3685.09715119792, 3745.9250814983548, 3765.6234376562675, 3769.208466222124, 3779.848806047727, 3823.8470461181796, 3844.6732027777393, 3866.516685414323, 3881.0103423626792, 3888.9805550972064, 4404.383386325917, 4413.492448368414, 4414.668899832525, 4432.933505997413, 4436.223660859337, 5136.948630513722, 5205.167173252337, 5231.633785635046, 5244.0, 5248.559419869779, 1284.916480428769, 1297.9740405192033, 1304.6803533134444, 1305.1907817153426, 1305.6017914536158, 835.3859912963967, 883.6156272021691, 887.9644814207488, 889.4929889962789, 892.1524551675805, 4426.18115648606, 4439.245328294198, 4461.475434406782, 4461.754603496839, 4469.100632231289, 5205.297284866495, 5245.26566280721, 5269.894602107936, 5279.788808447696, 5295.895680847297, 4170.72890262132, 4464.17412778637, 4470.240059189946, 4510.639148868083, 4513.232750432434, 2773.8474383908674, 2796.4267325198284, 2802.467531169071, 2811.493931092423, 2813.2263627502603, 4928.507149285115, 4935.777889994959, 4944.22008077092, 4947.282644016669, 4950.752462376867, 5197.610179236573, 5255.868603284948, 5328.973355133277, 5375.623706340597, 5495.148252020943, 5018.34027965179, 5027.79888804451, 5027.874303142377, 5030.320906677552, 5034.546890779848, 5400.92147475935, 5411.83764487066, 5420.75606597712, 5428.782848686008, 5434.75543600531, 5186.377634683862, 5306.628536002443, 5318.57451403879, 5350.678959262456, 5359.946400536101, 4800.423949125988, 4923.08922849274, 4985.252212168059, 5003.3495645565845, 5016.297718319365, 3747.194353214072, 3771.8868433947027, 3789.5599672124417, 3792.412176112707, 3800.7339486236806, 5258.579313654975, 5347.0, 5372.194170874243, 5382.954477298602, 5393.757280922289, 4913.729744863924, 5205.323307969939, 5234.476552344735, 5256.874547316336, 5266.762995665281, 5189.080040384931, 5241.397239317374, 5250.842474725764, 5252.107141785982, 5284.6524136346525, 5183.714895680623, 5190.766415511386, 5204.843854684365, 5205.765740541609, 5230.7384630769075, 4948.752562371939, 5247.344081989751, 5264.499872512105, 5310.176922577007, 5323.1216849219445, 5116.692998420107, 5126.384833819965, 5143.5627971622835, 5143.942423030818, 5154.252633368215], "value": 3931.0990095076218, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [619.043577672496, 637.8041172800995, 686.3068301015974, 704.3942209699658, 723.706898706024, 12022.759544809098, 12849.35753212338, 13097.73804523909, 13275.7344853103, 13380.0, 12854.293013884233, 13033.152845065064, 13085.0, 13331.800022999663, 13343.132696374729, 791.2957467853615, 820.5609998650723, 828.0146625515647, 828.6354004238146, 828.9875651865227, 799.564237490569, 818.4802650317032, 818.5088946632033, 828.6768160417442, 835.4611275727152, 851.9957400212991, 861.5606040919145, 861.9224269815716, 863.4603372891943, 865.7229686500291, 855.6662901468401, 856.1010938514535, 856.1353033436212, 856.9657213711444, 860.3934226370394, 625.2809269340249, 625.9718312675896, 627.3914303125961, 627.6767464755609, 628.8207860759661, 12913.031522635745, 13058.34708264583, 13146.0, 13155.408006639724, 13619.0, 11157.163212758995, 11226.607068752555, 11345.37600431976, 12834.422450989634, 13446.126001809851, 11635.88364116365, 11776.470058847372, 11900.107491938053, 11999.760004799942, 12142.332171730552, 4008.2183974125055, 4040.0909795296116, 4051.1290072634524, 4081.0851095543358, 4260.424842646234, 40512.9999999997, 49113.263301050516, 61280.69359653173, 61361.07958380628, 61948.99999999955, 9914.752131196778, 10018.849717254247, 10057.547410366551, 10192.596735293066, 10278.691639250834, 18046.007469589036, 18112.637747244982, 18180.63638727218, 18300.90849545744, 18335.90832045858, 6041.939580604226, 6108.083787431832, 6207.875842483125, 6321.620702757849, 6334.904976425358, 3405.880794172192, 3526.770759900598, 3546.5921419036627, 3547.592026916886, 3572.1248294168095, 2914.795964282522, 2978.136340461242, 3013.5329024001317, 3024.758019358446, 3041.4829478988627, 474.28383141456646, 476.04077783266746, 476.8974670445871, 485.114665735035, 490.97299648519675, 518.7458145508749, 532.292051571409, 547.5838362844188, 549.8872731090197, 552.8148070396381, 8205.548694821722, 8666.696665616673, 8804.647814087493, 8848.247898928534, 8880.911190888008, 611.2512172588632, 611.911272865432, 641.8812519683873, 643.2731013954202, 646.8318237258346, 4090.9795451022555, 4148.460700109027, 4166.937495937564, 4170.124273902515, 4274.422952901318, 62838.0, 63706.6814665933, 63978.6801066001, 65421.67289163524, 66462.0, 44668.55331446709, 48037.519624804, 56064.71967640136, 57262.14106788402, 57549.71225143931, 47715.76142119267, 50204.49795501998, 50875.74562127166, 51676.74161629168, 54265.45734542683, 7840.255175758294, 8175.264226219431, 8201.384896132933, 8239.752807415787, 8324.125966773401, 35999.28001440009, 36629.267414651025, 36901.63098368982, 37800.43299350457, 37830.62169378271, 38669.6133038666, 39103.0, 42901.356479653456, 47057.76471117623, 48092.27861582149, 11388.0, 11428.25716328436, 11579.768404631692, 11601.593944211912, 11780.587679431179, 12264.938675306566, 12454.626361209179, 12498.687532811753, 12728.045396595478, 13595.660108497366, 12277.447514861675, 12524.686882828002, 12565.246085234916, 12821.807672885101, 12970.740585188432, 11512.251703639236, 11892.702682433008, 12003.699907502383, 12139.332336721212, 12203.938980305042, 11211.271267367594, 11393.601223957121, 11423.428828558704, 11475.770484590095, 12143.453544590691, 8651.91348086511, 8908.643654253758, 9206.585703643219, 9219.222806179467, 9621.422714637145, 8609.397342185986, 9207.76980575491, 9231.76920576991, 9249.537523123683, 9337.0], "value": 6275.360514294374, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1578.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 116.0, 115.0, 115.0, 113.0, 114.0, 116.0, 116.0, 114.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 125.0, 126.0, 166.0, 126.0, 127.0, 129.0, 130.0, 158.0, 130.0], "value": 136.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 167.0, 166.0, 167.0, 176.0, 164.0, 164.0, 164.0, 164.0, 164.0], "value": 166.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 77.0, 118.0, 78.0, 78.0, 78.0, 77.0, 103.0, 79.0], "value": 84.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 106.0, 85.0, 83.0, 85.0, 99.0, 84.0, 85.0, 113.0], "value": 90.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 114.0, 113.0, 119.0, 114.0, 114.0, 114.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 151.0, 150.0, 150.0, 150.0, 149.0, 152.0, 150.0, 152.0], "value": 150.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 96.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 67.0, 66.0, 65.0, 66.0, 66.0, 66.0], "value": 65.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 65.0, 66.0, 66.0, 66.0, 66.0, 67.0, 66.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 88.0, 89.0, 87.0, 86.0, 94.0, 86.0, 89.0, 86.0], "value": 89.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 164.0, 136.0, 137.0, 150.0, 145.0, 140.0, 136.0, 192.0, 141.0], "value": 148.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 191.0, 182.0, 185.0, 186.0, 185.0, 192.0, 183.0, 180.0], "value": 185.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 75.0, 71.0, 72.0, 71.0, 72.0, 71.0, 70.0, 69.0, 68.0], "value": 71.0, "unit": "score"}]}]} 19:46:55 INFO - Return code: 0 19:46:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:46:55 ERROR - # TBPL SUCCESS # 19:46:55 INFO - Running post-action listener: _resource_record_post_action 19:46:55 INFO - [mozharness: 2017-02-23 03:46:55.557866Z] Finished run-tests step (success) 19:46:55 INFO - Running post-run listener: _resource_record_post_run 19:46:55 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 0; Write bytes: 363065344; Read time: 0; Write time: 332728 19:46:55 INFO - TinderboxPrint: CPU usage
13.5% 19:46:55 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 19:46:55 INFO - TinderboxPrint: I/O write bytes / time
363,065,344 / 332,728 19:46:55 INFO - TinderboxPrint: CPU idle
8,285.5 (86.5%) 19:46:55 INFO - TinderboxPrint: CPU user
1,233.0 (12.9%) 19:46:55 INFO - TinderboxPrint: Swap in / out
0 / 0 19:46:55 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107724800; Read time: 0; Write time: 133364 19:46:55 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 255340544; Read time: 0; Write time: 199364 19:46:56 INFO - Running post-run listener: _upload_blobber_files 19:46:56 INFO - Blob upload gear active. 19:46:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:46:56 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 19:46:56 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'] 19:46:56 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 19:46:56 INFO - (blobuploader) - INFO - Open directory for files ... 19:46:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:46:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:46:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:46:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:46:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:46:57 INFO - (blobuploader) - INFO - Done attempting. 19:46:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:46:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:46:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:47:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:01 INFO - (blobuploader) - INFO - Done attempting. 19:47:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:47:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:47:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:01 INFO - (blobuploader) - INFO - Done attempting. 19:47:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:47:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:47:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:02 INFO - (blobuploader) - INFO - Done attempting. 19:47:02 INFO - (blobuploader) - INFO - Iteration through files over. 19:47:02 INFO - Return code: 0 19:47:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:47:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:47:02 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa5db23cfbeee6132a188e37fb04305c6a0235f606d8fe7d7295c016de72779068fbd0da25350048927fdc5819e27c2b4eb9c3118e148a4181754abda9de843e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/40dc4580ce51e91eecc0c1a07309883f4544d1b62dde2af445e9ee5ad52c0094d30956accf53e379b8b834479833cddec070d8c836db2f67023c65632df406cc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f6b59d51eb3c6acc75d7e1808309638c3d5e1999d5ff24079800c6079a46a2c7619b325559eaf5f377e170fc78f090db2485cc38050687e7711b45afc6250880", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dfb18cbc3b663ee92ad0330e8bb75fcf10dfce87584b221c2c6c9301987af77b6238cc932a23c12a4743abf94af3eb6964cd5c1d59b4b044bae1756c46d0b36c"} 19:47:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:47:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:47:02 INFO - Contents: 19:47:02 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa5db23cfbeee6132a188e37fb04305c6a0235f606d8fe7d7295c016de72779068fbd0da25350048927fdc5819e27c2b4eb9c3118e148a4181754abda9de843e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/40dc4580ce51e91eecc0c1a07309883f4544d1b62dde2af445e9ee5ad52c0094d30956accf53e379b8b834479833cddec070d8c836db2f67023c65632df406cc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f6b59d51eb3c6acc75d7e1808309638c3d5e1999d5ff24079800c6079a46a2c7619b325559eaf5f377e170fc78f090db2485cc38050687e7711b45afc6250880", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dfb18cbc3b663ee92ad0330e8bb75fcf10dfce87584b221c2c6c9301987af77b6238cc932a23c12a4743abf94af3eb6964cd5c1d59b4b044bae1756c46d0b36c"} 19:47:02 INFO - Running post-run listener: copy_logs_to_upload_dir 19:47:02 INFO - Copying logs to upload dir... 19:47:02 INFO - mkdir: /builds/slave/test/build/upload/logs 19:47:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.450560 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-22 19:47:02.577479) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-22 19:47:02.581755) ========= 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=20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa5db23cfbeee6132a188e37fb04305c6a0235f606d8fe7d7295c016de72779068fbd0da25350048927fdc5819e27c2b4eb9c3118e148a4181754abda9de843e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/40dc4580ce51e91eecc0c1a07309883f4544d1b62dde2af445e9ee5ad52c0094d30956accf53e379b8b834479833cddec070d8c836db2f67023c65632df406cc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f6b59d51eb3c6acc75d7e1808309638c3d5e1999d5ff24079800c6079a46a2c7619b325559eaf5f377e170fc78f090db2485cc38050687e7711b45afc6250880", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dfb18cbc3b663ee92ad0330e8bb75fcf10dfce87584b221c2c6c9301987af77b6238cc932a23c12a4743abf94af3eb6964cd5c1d59b4b044bae1756c46d0b36c"} build_url:https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011321 build_url: 'https://queue.taskcluster.net/v1/task/fziZoBLZQf6J64GfmjMy5g/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa5db23cfbeee6132a188e37fb04305c6a0235f606d8fe7d7295c016de72779068fbd0da25350048927fdc5819e27c2b4eb9c3118e148a4181754abda9de843e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/40dc4580ce51e91eecc0c1a07309883f4544d1b62dde2af445e9ee5ad52c0094d30956accf53e379b8b834479833cddec070d8c836db2f67023c65632df406cc", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f6b59d51eb3c6acc75d7e1808309638c3d5e1999d5ff24079800c6079a46a2c7619b325559eaf5f377e170fc78f090db2485cc38050687e7711b45afc6250880", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dfb18cbc3b663ee92ad0330e8bb75fcf10dfce87584b221c2c6c9301987af77b6238cc932a23c12a4743abf94af3eb6964cd5c1d59b4b044bae1756c46d0b36c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-22 19:47:02.609087) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:47:02.609416) ========= 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=20e20d796406ba5117c4a6f1000001ba-1487820349.643316-1173630639 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004625 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 19:47:02.659039) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 19:47:02.659355) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 19:47:02.659630) ========= ========= Total master_lag: 0.21 =========