builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-032 starttime: 1487685268.19 results: success (0) revision: e25127db470ca6f01bd9650b347a369e18e55a45 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-21 05:54:28.193241) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-21 05:54:28.193739) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-21 05:54:28.194017) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-21 05:54:28.206779) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 05:54:28.207040) ========= 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=745a4373a2a16df560f216e000000264-1487685267.749100-1152801385 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-21 05:54:28-- 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% 37.2M=0s 2017-02-21 05:54:28 (37.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092735 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 05:54:28.310851) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 05:54:28.311151) ========= 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=745a4373a2a16df560f216e000000264-1487685267.749100-1152801385 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015049 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 05:54:28.360085) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 05:54:28.360423) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e25127db470ca6f01bd9650b347a369e18e55a45 --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 e25127db470ca6f01bd9650b347a369e18e55a45 --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=745a4373a2a16df560f216e000000264-1487685267.749100-1152801385 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-21 05:54:28,605 truncating revision to first 12 chars 2017-02-21 05:54:28,605 Setting DEBUG logging. 2017-02-21 05:54:28,605 attempt 1/10 2017-02-21 05:54:28,605 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e25127db470c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-21 05:54:29,017 unpacking tar archive at: mozilla-central-e25127db470c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.716641 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 05:54:29.089683) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-21 05:54:29.090010) ========= script_repo_revision: e25127db470ca6f01bd9650b347a369e18e55a45 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-21 05:54:29.090429) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-21 05:54:29.090719) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-21 05:54:29.100798) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-02-21 05:54:29.101072) ========= /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=745a4373a2a16df560f216e000000264-1487685267.749100-1152801385 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:54:29 INFO - MultiFileLogger online at 20170221 05:54:29 in /builds/slave/test 05:54:29 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 05:54:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:54:29 INFO - {'append_to_log': False, 05:54:29 INFO - 'base_work_dir': '/builds/slave/test', 05:54:29 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 05:54:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:54:29 INFO - 'branch': 'Firefox-Non-PGO', 05:54:29 INFO - 'buildbot_json_path': 'buildprops.json', 05:54:29 INFO - 'config_files': ('talos/linux_config.py',), 05:54:29 INFO - 'default_actions': ('clobber', 05:54:29 INFO - 'read-buildbot-config', 05:54:29 INFO - 'download-and-extract', 05:54:29 INFO - 'populate-webroot', 05:54:29 INFO - 'create-virtualenv', 05:54:29 INFO - 'install', 05:54:29 INFO - 'run-tests'), 05:54:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:54:29 INFO - 'download_minidump_stackwalk': True, 05:54:29 INFO - 'download_symbols': 'ondemand', 05:54:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:54:29 INFO - 'tooltool.py': '/tools/tooltool.py', 05:54:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:54:29 INFO - '/tools/misc-python/virtualenv.py')}, 05:54:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:54:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:54:29 INFO - 'gecko_profile': False, 05:54:29 INFO - 'gecko_profile_interval': 0, 05:54:29 INFO - 'installer_path': 'installer.exe', 05:54:29 INFO - 'log_level': 'info', 05:54:29 INFO - 'log_name': 'talos', 05:54:29 INFO - 'log_to_console': True, 05:54:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:54:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:54:29 INFO - 'opt_config_files': (), 05:54:29 INFO - 'pip_index': False, 05:54:29 INFO - 'suite': 'dromaeojs-e10s', 05:54:29 INFO - 'system_bits': '32', 05:54:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:54:29 INFO - 'title': 'talos-linux64-ix-032', 05:54:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:54:29 INFO - 'use_talos_json': True, 05:54:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:54:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:54:29 INFO - 'work_dir': 'build'} 05:54:29 INFO - [mozharness: 2017-02-21 13:54:29.434585Z] Running clobber step. 05:54:29 INFO - Running pre-action listener: _resource_record_pre_action 05:54:29 INFO - Running main action method: clobber 05:54:29 INFO - rmtree: /builds/slave/test/build 05:54:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:54:30 INFO - Running post-action listener: _resource_record_post_action 05:54:30 INFO - [mozharness: 2017-02-21 13:54:30.354934Z] Finished clobber step (success) 05:54:30 INFO - [mozharness: 2017-02-21 13:54:30.355035Z] Running read-buildbot-config step. 05:54:30 INFO - Running pre-action listener: _resource_record_pre_action 05:54:30 INFO - Running main action method: read_buildbot_config 05:54:30 INFO - Using buildbot properties: 05:54:30 INFO - { 05:54:30 INFO - "project": "", 05:54:30 INFO - "product": "firefox", 05:54:30 INFO - "who": "cbook@mozilla.com", 05:54:30 INFO - "installer_path": "public/build/target.tar.bz2", 05:54:30 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:54:30 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 05:54:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 05:54:30 INFO - "stage_platform": "linux64", 05:54:30 INFO - "basedir": "/builds/slave/test", 05:54:30 INFO - "buildnumber": 708, 05:54:30 INFO - "platform": "ubuntu64_hw", 05:54:30 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:54:30 INFO - "slavebuilddir": "test", 05:54:30 INFO - "taskId": "OlteLbIPTz6yYIVkmOFjSA", 05:54:30 INFO - "branch": "mozilla-central", 05:54:30 INFO - "script_repo_revision": "production", 05:54:30 INFO - "revision": "e25127db470ca6f01bd9650b347a369e18e55a45", 05:54:30 INFO - "slavename": "talos-linux64-ix-032", 05:54:30 INFO - "repo_path": "mozilla-central" 05:54:30 INFO - } 05:54:30 INFO - Finding installer, test and symbols from parent task. 05:54:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OlteLbIPTz6yYIVkmOFjSA'}, attempt #1 05:54:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OlteLbIPTz6yYIVkmOFjSA'}, attempt #1 05:54:31 INFO - Task dependencies: dA2cOnyfR8CbckA5mVdflg 05:54:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg'}, attempt #1 05:54:31 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.tar.bz2 05:54:31 INFO - Set test_url: https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/test_packages.json 05:54:31 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.crashreporter-symbols.zip 05:54:31 INFO - Running post-action listener: _resource_record_post_action 05:54:31 INFO - [mozharness: 2017-02-21 13:54:31.737739Z] Finished read-buildbot-config step (success) 05:54:31 INFO - [mozharness: 2017-02-21 13:54:31.737963Z] Running download-and-extract step. 05:54:31 INFO - Running pre-action listener: _resource_record_pre_action 05:54:31 INFO - Running main action method: download_and_extract 05:54:31 INFO - mkdir: /builds/slave/test/build/tests 05:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:54:31 INFO - trying https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.test_packages.json 05:54:31 INFO - Downloading https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:54:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:54:32 INFO - Downloaded 896 bytes. 05:54:32 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:54:32 INFO - Using the following test package requirements: 05:54:32 INFO - {u'common': [u'target.common.tests.zip'], 05:54:32 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:54:32 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:54:32 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:54:32 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:54:32 INFO - u'mozbase': [u'target.common.tests.zip'], 05:54:32 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:54:32 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:54:32 INFO - u'web-platform': [u'target.common.tests.zip', 05:54:32 INFO - u'target.web-platform.tests.zip'], 05:54:32 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:54:32 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:54:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.common.tests.zip 05:54:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:54:32 INFO - Fetch https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.common.tests.zip into memory 05:54:33 INFO - Expected file size: 57165802 05:54:33 INFO - Obtained file size: 57165802 05:54:43 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:54:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.common.tests.zip 05:54:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:54:43 INFO - Fetch https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.common.tests.zip into memory 05:54:44 INFO - Expected file size: 57165802 05:54:44 INFO - Obtained file size: 57165802 05:54:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.talos.tests.zip 05:54:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:54:54 INFO - Fetch https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.talos.tests.zip into memory 05:54:55 INFO - Expected file size: 14404319 05:54:55 INFO - Obtained file size: 14404319 05:54:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:54:56 INFO - trying https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.tar.bz2 05:54:56 INFO - Downloading https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:54:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:54:57 INFO - Downloaded 61551908 bytes. 05:54:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.tar.bz2 05:54:57 INFO - mkdir: /builds/slave/test/properties 05:54:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:54:57 INFO - Writing to file /builds/slave/test/properties/build_url 05:54:57 INFO - Contents: 05:54:57 INFO - build_url:https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.tar.bz2 05:54:57 INFO - Running post-action listener: _resource_record_post_action 05:54:57 INFO - Running post-action listener: set_extra_try_arguments 05:54:57 INFO - [mozharness: 2017-02-21 13:54:57.900552Z] Finished download-and-extract step (success) 05:54:57 INFO - [mozharness: 2017-02-21 13:54:57.900649Z] Running populate-webroot step. 05:54:57 INFO - Running pre-action listener: _resource_record_pre_action 05:54:57 INFO - Running main action method: populate_webroot 05:54:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:54:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:54:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:54:57 INFO - '--tppagecycles', 05:54:57 INFO - '7'], 05:54:57 INFO - 'tests': ['tsvgm']}}, 05:54:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:54:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:54:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:54:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:54:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:54:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:54:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:54:57 INFO - 'talos_options': ['--disable-e10s'], 05:54:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:54:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:54:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:54:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:54:57 INFO - 'talos_options': ['--disable-e10s'], 05:54:57 INFO - 'tests': ['damp', 'tps']}, 05:54:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:54:57 INFO - 'tests': ['damp', 'tps']}, 05:54:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:54:57 INFO - 'tests': ['dromaeo_dom']}, 05:54:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:54:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:54:57 INFO - 'tests': ['basic_compositor_video']}, 05:54:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:54:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:54:57 INFO - 'tests': ['a11yr', 05:54:57 INFO - 'ts_paint', 05:54:57 INFO - 'tpaint', 05:54:57 INFO - 'sessionrestore', 05:54:57 INFO - 'sessionrestore_no_auto_restore', 05:54:57 INFO - 'tabpaint']}, 05:54:57 INFO - 'other-e10s': {'tests': ['a11yr', 05:54:57 INFO - 'ts_paint', 05:54:57 INFO - 'tpaint', 05:54:57 INFO - 'sessionrestore', 05:54:57 INFO - 'sessionrestore_no_auto_restore', 05:54:57 INFO - 'tabpaint']}, 05:54:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:54:57 INFO - 'ts_paint', 05:54:57 INFO - 'tpaint', 05:54:57 INFO - 'sessionrestore', 05:54:57 INFO - 'sessionrestore_no_auto_restore', 05:54:57 INFO - 'tabpaint']}, 05:54:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:54:57 INFO - 'ts_paint', 05:54:57 INFO - 'tpaint', 05:54:57 INFO - 'sessionrestore', 05:54:57 INFO - 'sessionrestore_no_auto_restore', 05:54:57 INFO - 'tabpaint']}, 05:54:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:54:57 INFO - 'tests': ['a11yr', 05:54:57 INFO - 'ts_paint', 05:54:57 INFO - 'tpaint', 05:54:57 INFO - 'sessionrestore', 05:54:57 INFO - 'sessionrestore_no_auto_restore', 05:54:57 INFO - 'tabpaint']}, 05:54:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:54:57 INFO - 'tests': ['a11yr', 05:54:57 INFO - 'ts_paint', 05:54:57 INFO - 'tpaint', 05:54:57 INFO - 'sessionrestore', 05:54:57 INFO - 'sessionrestore_no_auto_restore', 05:54:57 INFO - 'tabpaint']}, 05:54:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:54:57 INFO - 'tests': ['tsvgx', 05:54:57 INFO - 'tsvgr_opacity', 05:54:57 INFO - 'tart', 05:54:57 INFO - 'tscrollx', 05:54:57 INFO - 'cart', 05:54:57 INFO - 'tsvg_static']}, 05:54:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:54:57 INFO - 'tsvgr_opacity', 05:54:57 INFO - 'tart', 05:54:57 INFO - 'tscrollx', 05:54:57 INFO - 'cart', 05:54:57 INFO - 'tsvg_static']}, 05:54:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:54:57 INFO - 'talos_options': ['--disable-e10s'], 05:54:57 INFO - 'tests': ['tp5o']}, 05:54:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:54:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:54:57 INFO - 'talos_options': ['--disable-e10s', 05:54:57 INFO - '--xperf_path', 05:54:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:54:57 INFO - 'tests': ['tp5n']}, 05:54:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:54:57 INFO - 'talos_options': ['--xperf_path', 05:54:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:54:57 INFO - 'tests': ['tp5n']}}, 05:54:57 INFO - 'talos.zip': {'path': '', 05:54:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:54:57 INFO - Running post-action listener: _resource_record_post_action 05:54:57 INFO - [mozharness: 2017-02-21 13:54:57.913782Z] Finished populate-webroot step (success) 05:54:57 INFO - [mozharness: 2017-02-21 13:54:57.913873Z] Running create-virtualenv step. 05:54:57 INFO - Running pre-action listener: _resource_record_pre_action 05:54:57 INFO - Running main action method: create_virtualenv 05:54:57 INFO - Creating virtualenv /builds/slave/test/build/venv 05:54:57 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:54:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:54:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:54:58 INFO - Using real prefix '/usr' 05:54:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:54:58 INFO - Installing distribute.............................................................................................................................................................................................done. 05:55:00 INFO - Installing pip.................done. 05:55:00 INFO - Return code: 0 05:55:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:55:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:55:00 INFO - Reading from file tmpfile_stdout 05:55:00 INFO - Output received: 05:55:00 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:55:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:55:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7d290>, '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 0x1c7db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c7e040>, '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': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:55:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:55:00 INFO - Using env: {'DISPLAY': ':0', 05:55:00 INFO - 'HOME': '/home/cltbld', 05:55:00 INFO - 'LANG': 'en_US.UTF-8', 05:55:00 INFO - 'LANGUAGE': 'en_US:en', 05:55:00 INFO - 'LOGNAME': 'cltbld', 05:55:00 INFO - 'MAIL': '/var/mail/cltbld', 05:55:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:00 INFO - 'MOZ_NO_REMOTE': '1', 05:55:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:00 INFO - 'NO_EM_RESTART': '1', 05:55:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:00 INFO - 'PWD': '/builds/slave/test', 05:55:00 INFO - 'SHELL': '/bin/bash', 05:55:00 INFO - 'SHLVL': '1', 05:55:00 INFO - 'TERM': 'linux', 05:55:00 INFO - 'TMOUT': '86400', 05:55:00 INFO - 'USER': 'cltbld', 05:55:00 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 05:55:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:00 INFO - '_': '/tools/buildbot/bin/python'} 05:55:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:00 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:55:00 INFO - Cleaning up... 05:55:00 INFO - Return code: 0 05:55:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:55:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7d290>, '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 0x1c7db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c7e040>, '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': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:55:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:55:01 INFO - Using env: {'DISPLAY': ':0', 05:55:01 INFO - 'HOME': '/home/cltbld', 05:55:01 INFO - 'LANG': 'en_US.UTF-8', 05:55:01 INFO - 'LANGUAGE': 'en_US:en', 05:55:01 INFO - 'LOGNAME': 'cltbld', 05:55:01 INFO - 'MAIL': '/var/mail/cltbld', 05:55:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:01 INFO - 'MOZ_NO_REMOTE': '1', 05:55:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:01 INFO - 'NO_EM_RESTART': '1', 05:55:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:01 INFO - 'PWD': '/builds/slave/test', 05:55:01 INFO - 'SHELL': '/bin/bash', 05:55:01 INFO - 'SHLVL': '1', 05:55:01 INFO - 'TERM': 'linux', 05:55:01 INFO - 'TMOUT': '86400', 05:55:01 INFO - 'USER': 'cltbld', 05:55:01 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 05:55:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:01 INFO - '_': '/tools/buildbot/bin/python'} 05:55:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:01 INFO - Downloading/unpacking psutil>=3.1.1 05:55:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:55:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:55:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:55:03 INFO - Installing collected packages: psutil 05:55:03 INFO - Running setup.py install for psutil 05:55:03 INFO - building 'psutil._psutil_linux' extension 05:55:03 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:55:03 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:55:03 INFO - building 'psutil._psutil_posix' extension 05:55:03 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:55:03 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:55:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:55:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:55:03 INFO - Successfully installed psutil 05:55:03 INFO - Cleaning up... 05:55:03 INFO - Return code: 0 05:55:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:03 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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7d290>, '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 0x1c7db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c7e040>, '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': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:55:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:55:03 INFO - Using env: {'DISPLAY': ':0', 05:55:03 INFO - 'HOME': '/home/cltbld', 05:55:03 INFO - 'LANG': 'en_US.UTF-8', 05:55:03 INFO - 'LANGUAGE': 'en_US:en', 05:55:03 INFO - 'LOGNAME': 'cltbld', 05:55:03 INFO - 'MAIL': '/var/mail/cltbld', 05:55:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:03 INFO - 'MOZ_NO_REMOTE': '1', 05:55:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:03 INFO - 'NO_EM_RESTART': '1', 05:55:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:03 INFO - 'PWD': '/builds/slave/test', 05:55:03 INFO - 'SHELL': '/bin/bash', 05:55:03 INFO - 'SHLVL': '1', 05:55:03 INFO - 'TERM': 'linux', 05:55:03 INFO - 'TMOUT': '86400', 05:55:03 INFO - 'USER': 'cltbld', 05:55:03 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 05:55:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:03 INFO - '_': '/tools/buildbot/bin/python'} 05:55:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:55:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:55:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:55:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:55:06 INFO - Installing collected packages: mozsystemmonitor 05:55:06 INFO - Running setup.py install for mozsystemmonitor 05:55:06 INFO - Successfully installed mozsystemmonitor 05:55:06 INFO - Cleaning up... 05:55:06 INFO - Return code: 0 05:55:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:06 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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7d290>, '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 0x1c7db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c7e040>, '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': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:55:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:55:06 INFO - Using env: {'DISPLAY': ':0', 05:55:06 INFO - 'HOME': '/home/cltbld', 05:55:06 INFO - 'LANG': 'en_US.UTF-8', 05:55:06 INFO - 'LANGUAGE': 'en_US:en', 05:55:06 INFO - 'LOGNAME': 'cltbld', 05:55:06 INFO - 'MAIL': '/var/mail/cltbld', 05:55:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:06 INFO - 'MOZ_NO_REMOTE': '1', 05:55:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:06 INFO - 'NO_EM_RESTART': '1', 05:55:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:06 INFO - 'PWD': '/builds/slave/test', 05:55:06 INFO - 'SHELL': '/bin/bash', 05:55:06 INFO - 'SHLVL': '1', 05:55:06 INFO - 'TERM': 'linux', 05:55:06 INFO - 'TMOUT': '86400', 05:55:06 INFO - 'USER': 'cltbld', 05:55:06 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 05:55:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:06 INFO - '_': '/tools/buildbot/bin/python'} 05:55:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:06 INFO - Downloading/unpacking jsonschema==2.5.1 05:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:55:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:55:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:08 INFO - Downloading functools32-3.2.3-2.tar.gz 05:55:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:55:08 INFO - warning: no files found matching '*.txt' 05:55:08 INFO - no previously-included directories found matching 'build' 05:55:08 INFO - no previously-included directories found matching 'dist' 05:55:08 INFO - no previously-included directories found matching '.git*' 05:55:08 INFO - Installing collected packages: jsonschema, functools32 05:55:08 INFO - Running setup.py install for functools32 05:55:08 INFO - warning: no files found matching '*.txt' 05:55:08 INFO - no previously-included directories found matching 'build' 05:55:08 INFO - no previously-included directories found matching 'dist' 05:55:08 INFO - no previously-included directories found matching '.git*' 05:55:08 INFO - Successfully installed jsonschema functools32 05:55:08 INFO - Cleaning up... 05:55:09 INFO - Return code: 0 05:55:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:55:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:09 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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7d290>, '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 0x1c7db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c7e040>, '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': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:55:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:55:09 INFO - Using env: {'DISPLAY': ':0', 05:55:09 INFO - 'HOME': '/home/cltbld', 05:55:09 INFO - 'LANG': 'en_US.UTF-8', 05:55:09 INFO - 'LANGUAGE': 'en_US:en', 05:55:09 INFO - 'LOGNAME': 'cltbld', 05:55:09 INFO - 'MAIL': '/var/mail/cltbld', 05:55:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:09 INFO - 'MOZ_NO_REMOTE': '1', 05:55:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:09 INFO - 'NO_EM_RESTART': '1', 05:55:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:09 INFO - 'PWD': '/builds/slave/test', 05:55:09 INFO - 'SHELL': '/bin/bash', 05:55:09 INFO - 'SHLVL': '1', 05:55:09 INFO - 'TERM': 'linux', 05:55:09 INFO - 'TMOUT': '86400', 05:55:09 INFO - 'USER': 'cltbld', 05:55:09 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 05:55:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:09 INFO - '_': '/tools/buildbot/bin/python'} 05:55:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:55:09 INFO - Cleaning up... 05:55:09 INFO - Return code: 0 05:55:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:55:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:09 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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7d290>, '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 0x1c7db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c7e040>, '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': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:55:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:55:09 INFO - Using env: {'DISPLAY': ':0', 05:55:09 INFO - 'HOME': '/home/cltbld', 05:55:09 INFO - 'LANG': 'en_US.UTF-8', 05:55:09 INFO - 'LANGUAGE': 'en_US:en', 05:55:09 INFO - 'LOGNAME': 'cltbld', 05:55:09 INFO - 'MAIL': '/var/mail/cltbld', 05:55:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:09 INFO - 'MOZ_NO_REMOTE': '1', 05:55:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:09 INFO - 'NO_EM_RESTART': '1', 05:55:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:09 INFO - 'PWD': '/builds/slave/test', 05:55:09 INFO - 'SHELL': '/bin/bash', 05:55:09 INFO - 'SHLVL': '1', 05:55:09 INFO - 'TERM': 'linux', 05:55:09 INFO - 'TMOUT': '86400', 05:55:09 INFO - 'USER': 'cltbld', 05:55:09 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 05:55:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:09 INFO - '_': '/tools/buildbot/bin/python'} 05:55:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:09 INFO - Downloading/unpacking blobuploader==1.2.4 05:55:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:11 INFO - Downloading blobuploader-1.2.4.tar.gz 05:55:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:55:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:55:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:12 INFO - Downloading docopt-0.6.1.tar.gz 05:55:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:55:12 INFO - Installing collected packages: blobuploader, requests, docopt 05:55:12 INFO - Running setup.py install for blobuploader 05:55:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:55:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:55:12 INFO - Running setup.py install for requests 05:55:13 INFO - Running setup.py install for docopt 05:55:13 INFO - Successfully installed blobuploader requests docopt 05:55:13 INFO - Cleaning up... 05:55:13 INFO - Return code: 0 05:55:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:55:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:14 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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7d290>, '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 0x1c7db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c7e040>, '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': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:55:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:55:14 INFO - Using env: {'DISPLAY': ':0', 05:55:14 INFO - 'HOME': '/home/cltbld', 05:55:14 INFO - 'LANG': 'en_US.UTF-8', 05:55:14 INFO - 'LANGUAGE': 'en_US:en', 05:55:14 INFO - 'LOGNAME': 'cltbld', 05:55:14 INFO - 'MAIL': '/var/mail/cltbld', 05:55:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:14 INFO - 'MOZ_NO_REMOTE': '1', 05:55:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:14 INFO - 'NO_EM_RESTART': '1', 05:55:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:14 INFO - 'PWD': '/builds/slave/test', 05:55:14 INFO - 'SHELL': '/bin/bash', 05:55:14 INFO - 'SHLVL': '1', 05:55:14 INFO - 'TERM': 'linux', 05:55:14 INFO - 'TMOUT': '86400', 05:55:14 INFO - 'USER': 'cltbld', 05:55:14 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 05:55:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:14 INFO - '_': '/tools/buildbot/bin/python'} 05:55:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:55:14 INFO - Running setup.py (path:/tmp/pip-DcnSQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:55:14 INFO - Running setup.py (path:/tmp/pip-qttROa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:55:14 INFO - Running setup.py (path:/tmp/pip-_HwyF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:55:14 INFO - Running setup.py (path:/tmp/pip-1yD30D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:55:14 INFO - Running setup.py (path:/tmp/pip-bQHpPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:55:14 INFO - Running setup.py (path:/tmp/pip-dzjook-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:55:15 INFO - Running setup.py (path:/tmp/pip-PwbqUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:55:15 INFO - Running setup.py (path:/tmp/pip-XcbBTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:55:15 INFO - Running setup.py (path:/tmp/pip-4BMVK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:55:15 INFO - Running setup.py (path:/tmp/pip-_pWexq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:55:15 INFO - Running setup.py (path:/tmp/pip-efPa8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:55:15 INFO - Running setup.py (path:/tmp/pip-ftGYrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:55:15 INFO - Running setup.py (path:/tmp/pip-5ZwS8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:55:15 INFO - Running setup.py (path:/tmp/pip-oKgYwO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:15 INFO - Running setup.py (path:/tmp/pip-IlOdFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:55:15 INFO - Running setup.py (path:/tmp/pip-4na2VH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:55:15 INFO - Running setup.py (path:/tmp/pip-oeM7fC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:55:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:55:15 INFO - Running setup.py install for manifestparser 05:55:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:55:15 INFO - Running setup.py install for mozcrash 05:55:16 INFO - Running setup.py install for mozdebug 05:55:16 INFO - Running setup.py install for mozdevice 05:55:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Running setup.py install for mozfile 05:55:16 INFO - Running setup.py install for mozhttpd 05:55:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Running setup.py install for mozinfo 05:55:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Running setup.py install for mozInstall 05:55:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Running setup.py install for mozleak 05:55:16 INFO - Running setup.py install for mozlog 05:55:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Running setup.py install for moznetwork 05:55:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:55:16 INFO - Running setup.py install for mozprocess 05:55:17 INFO - Running setup.py install for mozprofile 05:55:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:55:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:55:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:55:17 INFO - Running setup.py install for mozrunner 05:55:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:55:17 INFO - Running setup.py install for mozscreenshot 05:55:17 INFO - Running setup.py install for moztest 05:55:17 INFO - Running setup.py install for mozversion 05:55:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:55:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:55:17 INFO - Cleaning up... 05:55:17 INFO - Return code: 0 05:55:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:55:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:17 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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7d290>, '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 0x1c7db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c7e040>, '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': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:55:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:55:17 INFO - Using env: {'DISPLAY': ':0', 05:55:17 INFO - 'HOME': '/home/cltbld', 05:55:17 INFO - 'LANG': 'en_US.UTF-8', 05:55:17 INFO - 'LANGUAGE': 'en_US:en', 05:55:17 INFO - 'LOGNAME': 'cltbld', 05:55:17 INFO - 'MAIL': '/var/mail/cltbld', 05:55:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:17 INFO - 'MOZ_NO_REMOTE': '1', 05:55:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:17 INFO - 'NO_EM_RESTART': '1', 05:55:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:17 INFO - 'PWD': '/builds/slave/test', 05:55:17 INFO - 'SHELL': '/bin/bash', 05:55:17 INFO - 'SHLVL': '1', 05:55:17 INFO - 'TERM': 'linux', 05:55:17 INFO - 'TMOUT': '86400', 05:55:17 INFO - 'USER': 'cltbld', 05:55:17 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 05:55:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:17 INFO - '_': '/tools/buildbot/bin/python'} 05:55:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:55:18 INFO - Running setup.py (path:/tmp/pip-eHhxC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:55:18 INFO - Running setup.py (path:/tmp/pip-vYCiD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:55:18 INFO - Running setup.py (path:/tmp/pip-DGN2J9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:55:18 INFO - Running setup.py (path:/tmp/pip-UUapQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:55:18 INFO - Running setup.py (path:/tmp/pip-MWaPaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:55:18 INFO - Running setup.py (path:/tmp/pip-WQagiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:55:18 INFO - Running setup.py (path:/tmp/pip-LIg79L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:55:18 INFO - Running setup.py (path:/tmp/pip-08ytk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:55:18 INFO - Running setup.py (path:/tmp/pip-51vaNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:55:18 INFO - Running setup.py (path:/tmp/pip-jPffpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:55:18 INFO - Running setup.py (path:/tmp/pip-dmVFD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:55:18 INFO - Running setup.py (path:/tmp/pip-qlINQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:55:18 INFO - Running setup.py (path:/tmp/pip-pX_stt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:55:18 INFO - Running setup.py (path:/tmp/pip-wi91m_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:19 INFO - Running setup.py (path:/tmp/pip-fT2GGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:55:19 INFO - Running setup.py (path:/tmp/pip-MqdjKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:55:19 INFO - Running setup.py (path:/tmp/pip-gGsExj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:55:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:55:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:55:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:21 INFO - Downloading blessings-1.6.tar.gz 05:55:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:55:21 INFO - Installing collected packages: blessings 05:55:21 INFO - Running setup.py install for blessings 05:55:21 INFO - Successfully installed blessings 05:55:21 INFO - Cleaning up... 05:55:21 INFO - Return code: 0 05:55:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:55:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:55:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:55:22 INFO - Reading from file tmpfile_stdout 05:55:22 INFO - Current package versions: 05:55:22 INFO - argparse == 1.2.1 05:55:22 INFO - blessings == 1.6 05:55:22 INFO - blobuploader == 1.2.4 05:55:22 INFO - docopt == 0.6.1 05:55:22 INFO - functools32 == 3.2.3-2 05:55:22 INFO - jsonschema == 2.5.1 05:55:22 INFO - manifestparser == 1.1 05:55:22 INFO - mozInstall == 1.12 05:55:22 INFO - mozcrash == 1.0 05:55:22 INFO - mozdebug == 0.1 05:55:22 INFO - mozdevice == 0.49 05:55:22 INFO - mozfile == 1.2 05:55:22 INFO - mozhttpd == 0.7 05:55:22 INFO - mozinfo == 0.9 05:55:22 INFO - mozleak == 0.1 05:55:22 INFO - mozlog == 3.4 05:55:22 INFO - moznetwork == 0.27 05:55:22 INFO - mozprocess == 0.25 05:55:22 INFO - mozprofile == 0.28 05:55:22 INFO - mozrunner == 6.13 05:55:22 INFO - mozscreenshot == 0.1 05:55:22 INFO - mozsystemmonitor == 0.3 05:55:22 INFO - moztest == 0.8 05:55:22 INFO - mozversion == 1.4 05:55:22 INFO - psutil == 3.1.1 05:55:22 INFO - requests == 1.2.3 05:55:22 INFO - wsgiref == 0.1.2 05:55:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:22 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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7d290>, '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 0x1c7db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c7e040>, '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': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:55:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:55:22 INFO - Using env: {'DISPLAY': ':0', 05:55:22 INFO - 'HOME': '/home/cltbld', 05:55:22 INFO - 'LANG': 'en_US.UTF-8', 05:55:22 INFO - 'LANGUAGE': 'en_US:en', 05:55:22 INFO - 'LOGNAME': 'cltbld', 05:55:22 INFO - 'MAIL': '/var/mail/cltbld', 05:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:22 INFO - 'MOZ_NO_REMOTE': '1', 05:55:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:22 INFO - 'NO_EM_RESTART': '1', 05:55:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:22 INFO - 'PWD': '/builds/slave/test', 05:55:22 INFO - 'SHELL': '/bin/bash', 05:55:22 INFO - 'SHLVL': '1', 05:55:22 INFO - 'TERM': 'linux', 05:55:22 INFO - 'TMOUT': '86400', 05:55:22 INFO - 'USER': 'cltbld', 05:55:22 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 05:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:22 INFO - '_': '/tools/buildbot/bin/python'} 05:55:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:55:22 INFO - Cleaning up... 05:55:22 INFO - Return code: 0 05:55:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:55:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x19e21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1adaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c7fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c7d290>, '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 0x1c7db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c7e040>, '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': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:55:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:55:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:55:22 INFO - Using env: {'DISPLAY': ':0', 05:55:22 INFO - 'HOME': '/home/cltbld', 05:55:22 INFO - 'LANG': 'en_US.UTF-8', 05:55:22 INFO - 'LANGUAGE': 'en_US:en', 05:55:22 INFO - 'LOGNAME': 'cltbld', 05:55:22 INFO - 'MAIL': '/var/mail/cltbld', 05:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:22 INFO - 'MOZ_NO_REMOTE': '1', 05:55:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:22 INFO - 'NO_EM_RESTART': '1', 05:55:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:22 INFO - 'PWD': '/builds/slave/test', 05:55:22 INFO - 'SHELL': '/bin/bash', 05:55:22 INFO - 'SHLVL': '1', 05:55:22 INFO - 'TERM': 'linux', 05:55:22 INFO - 'TMOUT': '86400', 05:55:22 INFO - 'USER': 'cltbld', 05:55:22 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 05:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:22 INFO - '_': '/tools/buildbot/bin/python'} 05:55:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:55:22 INFO - Cleaning up... 05:55:22 INFO - Return code: 0 05:55:22 INFO - Running post-action listener: _resource_record_post_action 05:55:22 INFO - Running post-action listener: _start_resource_monitoring 05:55:22 INFO - Starting resource monitoring. 05:55:22 INFO - [mozharness: 2017-02-21 13:55:22.736232Z] Finished create-virtualenv step (success) 05:55:22 INFO - [mozharness: 2017-02-21 13:55:22.736703Z] Running install step. 05:55:22 INFO - Running pre-action listener: _resource_record_pre_action 05:55:22 INFO - Running main action method: install 05:55:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:55:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:55:22 INFO - Reading from file tmpfile_stdout 05:55:22 INFO - Detecting whether we're running mozinstall >=1.0... 05:55:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:55:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:55:23 INFO - Reading from file tmpfile_stdout 05:55:23 INFO - Output received: 05:55:23 INFO - Usage: mozinstall [options] installer 05:55:23 INFO - Options: 05:55:23 INFO - -h, --help show this help message and exit 05:55:23 INFO - -d DEST, --destination=DEST 05:55:23 INFO - Directory to install application into. [default: 05:55:23 INFO - "/builds/slave/test"] 05:55:23 INFO - --app=APP Application being installed. [default: firefox] 05:55:23 INFO - mkdir: /builds/slave/test/build/application 05:55:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:55:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:55:38 INFO - Reading from file tmpfile_stdout 05:55:38 INFO - Output received: 05:55:38 INFO - /builds/slave/test/build/application/firefox/firefox 05:55:38 INFO - Running post-action listener: _resource_record_post_action 05:55:38 INFO - [mozharness: 2017-02-21 13:55:38.460491Z] Finished install step (success) 05:55:38 INFO - [mozharness: 2017-02-21 13:55:38.460767Z] Running run-tests step. 05:55:38 INFO - Running pre-action listener: _resource_record_pre_action 05:55:38 INFO - Running main action method: run_tests 05:55:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:55:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:55:38 INFO - Python 2.7.3 05:55:38 INFO - Return code: 0 05:55:38 INFO - grabbing minidump binary from tooltool 05:55: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')]} 05:55:38 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 0x1c7d290>, '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 0x1c7db90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c7e040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:55:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:55:38 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:55:38 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:55:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:55:38 INFO - Return code: 0 05:55:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:55:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:55:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:55:38 INFO - ENV: RUST_BACKTRACE is now 1 05:55:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:55:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:55:38 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/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:55:38 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/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-032 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:55:38 INFO - Using env: {'DISPLAY': ':0', 05:55:38 INFO - 'HOME': '/home/cltbld', 05:55:38 INFO - 'LANG': 'en_US.UTF-8', 05:55:38 INFO - 'LANGUAGE': 'en_US:en', 05:55:38 INFO - 'LOGNAME': 'cltbld', 05:55:38 INFO - 'MAIL': '/var/mail/cltbld', 05:55:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:55:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:55:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:38 INFO - 'MOZ_NO_REMOTE': '1', 05:55:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:55:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:55:38 INFO - 'NO_EM_RESTART': '1', 05:55:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:55:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:55:38 INFO - 'PWD': '/builds/slave/test', 05:55:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:55:38 INFO - 'RUST_BACKTRACE': '1', 05:55:38 INFO - 'SHELL': '/bin/bash', 05:55:38 INFO - 'SHLVL': '1', 05:55:38 INFO - 'TERM': 'linux', 05:55:38 INFO - 'TMOUT': '86400', 05:55:38 INFO - 'USER': 'cltbld', 05:55:38 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1487685267.749100-1152801385', 05:55:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:55:38 INFO - '_': '/tools/buildbot/bin/python'} 05:55:38 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/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:55:38 INFO - mozversion application_buildid: 20170221131358 05:55:38 INFO - mozversion application_changeset: e25127db470ca6f01bd9650b347a369e18e55a45 05:55:38 INFO - mozversion application_display_name: Nightly 05:55:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:55:38 INFO - mozversion application_name: Firefox 05:55:38 INFO - mozversion application_remotingname: firefox 05:55:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:55:38 INFO - mozversion application_vendor: Mozilla 05:55:38 INFO - mozversion application_version: 54.0a1 05:55:38 INFO - mozversion platform_buildid: 20170221131358 05:55:38 INFO - mozversion platform_changeset: e25127db470ca6f01bd9650b347a369e18e55a45 05:55:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:55:38 INFO - mozversion platform_version: 54.0a1 05:55:38 INFO - using testdate: 1487685338 05:55:38 INFO - actual date: 1487685338 05:55:38 INFO - starting webserver on 'localhost:42357' 05:55:38 INFO - SUITE-START | Running 2 tests 05:55:38 INFO - TEST-START | dromaeo_css 05:55:38 INFO - operating with platform_type : linux_ 05:55:38 INFO - Initialising browser for dromaeo_css test... 05:55:38 INFO - TEST-INFO | started process 16906 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAOqjI2/profile http://localhost:42357/getInfo.html) 05:55:42 INFO - PROCESS | 16906 | Iterating over profile /tmp/tmpAOqjI2/profile 05:55:43 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:43 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/addons.json = Tue Feb 21 2017 05:55:41 GMT-0800 (PST) 05:55:43 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:43 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/extensions.json = Tue Feb 21 2017 05:55:41 GMT-0800 (PST) 05:55:43 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:43 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/places.sqlite-wal = Tue Feb 21 2017 05:55:43 GMT-0800 (PST) 05:55:43 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:43 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/key3.db = Tue Feb 21 2017 05:55:39 GMT-0800 (PST) 05:55:43 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:43 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/permissions.sqlite = Tue Feb 21 2017 05:55:40 GMT-0800 (PST) 05:55:43 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:43 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/localstore.rdf = Tue Feb 21 2017 05:54:55 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/startupCache = Tue Feb 21 2017 05:55:38 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | __metrics Screen width/height:1600/1200 05:55:44 INFO - PROCESS | 16906 | colorDepth:24 05:55:44 INFO - PROCESS | 16906 | Browser inner width/height: 1024/697 05:55:44 INFO - PROCESS | 16906 | __metrics 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/bookmarkbackups = Tue Feb 21 2017 05:55:41 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/thumbnails = Tue Feb 21 2017 05:55:41 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/cache2 = Tue Feb 21 2017 05:55:38 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/crashes = Tue Feb 21 2017 05:55:38 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/mimeTypes.rdf = Tue Feb 21 2017 05:55:42 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/prefs.js = Tue Feb 21 2017 05:54:55 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/places.sqlite-shm = Tue Feb 21 2017 05:55:40 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/pluginreg.dat = Tue Feb 21 2017 05:55:41 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/.parentlock = Tue Feb 21 2017 05:55:38 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/blocklist.xml = Tue Feb 21 2017 05:55:39 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/safebrowsing = Tue Feb 21 2017 05:55:44 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/extensions = Tue Feb 21 2017 05:55:39 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/frequencyCap.json = Tue Feb 21 2017 05:55:41 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/sessionCheckpoints.json = Tue Feb 21 2017 05:55:43 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/compatibility.ini = Tue Feb 21 2017 05:55:39 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/cert8.db = Tue Feb 21 2017 05:55:39 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/user.js = Tue Feb 21 2017 05:55:38 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/extensions.ini = Tue Feb 21 2017 05:55:39 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/content-prefs.sqlite = Tue Feb 21 2017 05:55:41 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/directoryLinks.json = Tue Feb 21 2017 05:55:41 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/secmod.db = Tue Feb 21 2017 05:55:39 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/gmp = Tue Feb 21 2017 05:55:39 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/minidumps = Tue Feb 21 2017 05:55:38 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | No birth date. Using mtime. 05:55:44 INFO - PROCESS | 16906 | Using date: /tmp/tmpAOqjI2/profile/places.sqlite = Tue Feb 21 2017 05:55:44 GMT-0800 (PST) 05:55:44 INFO - PROCESS | 16906 | Stat failure 05:55:45 ERROR - PROCESS | 16906 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 05:55:46 INFO - TEST-INFO | 16906: exit 0 05:55:46 INFO - Browser initialized. 05:55:46 INFO - Running cycle 1/1 for dromaeo_css test... 05:55:46 INFO - TEST-INFO | started process 17032 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAOqjI2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:55:58 INFO - PROCESS | 17032 | RSS: Main: 153853952 05:55:58 INFO - PROCESS | 17032 | 05:59:03 INFO - PROCESS | 17032 | Cycle 1(1): loaded http://localhost:42357/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42357/tests/dromaeo/cssquery-ext.html) 05:59:04 INFO - PROCESS | 17032 | RSS: Main: 165031936 05:59:04 INFO - PROCESS | 17032 | 06:00:47 INFO - PROCESS | 17032 | ************************* 06:00:47 INFO - PROCESS | 17032 | A coding exception was thrown and uncaught in a Task. 06:00:47 INFO - PROCESS | 17032 | 06:00:47 ERROR - PROCESS | 17032 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:00:47 INFO - PROCESS | 17032 | Full stack: 06:00:47 INFO - PROCESS | 17032 | ************************* 06:02:07 INFO - PROCESS | 17032 | Cycle 1(1): loaded http://localhost:42357/tests/dromaeo/cssquery-ext.html (next: http://localhost:42357/tests/dromaeo/cssquery-jquery.html) 06:02:07 INFO - PROCESS | 17032 | RSS: Main: 157478912 06:02:07 INFO - PROCESS | 17032 | 06:05:09 INFO - PROCESS | 17032 | Cycle 1(1): loaded http://localhost:42357/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42357/tests/dromaeo/cssquery-mootools.html) 06:05:10 INFO - PROCESS | 17032 | RSS: Main: 165814272 06:05:10 INFO - PROCESS | 17032 | 06:08:12 INFO - PROCESS | 17032 | Cycle 1(1): loaded http://localhost:42357/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42357/tests/dromaeo/cssquery-prototype.html) 06:08:13 INFO - PROCESS | 17032 | RSS: Main: 157593600 06:08:13 INFO - PROCESS | 17032 | 06:11:16 INFO - PROCESS | 17032 | Cycle 1(1): loaded http://localhost:42357/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42357/tests/dromaeo/cssquery-yui.html) 06:11:16 INFO - PROCESS | 17032 | RSS: Main: 165441536 06:11:16 INFO - PROCESS | 17032 | 06:14:19 INFO - PROCESS | 17032 | Cycle 1(1): loaded http://localhost:42357/tests/dromaeo/cssquery-yui.html (next: http://localhost:42357/tests/dromaeo/cssquery-dojo.html) 06:14:19 INFO - PROCESS | 17032 | RSS: Main: 158359552 06:14:19 INFO - PROCESS | 17032 | 06:14:19 INFO - PROCESS | 17032 | __start_tp_report 06:14:19 INFO - PROCESS | 17032 | _x_x_mozilla_page_load 06:14:19 INFO - PROCESS | 17032 | _x_x_mozilla_page_load_details 06:14:19 INFO - PROCESS | 17032 | |i|pagename|runs| 06:14:19 INFO - PROCESS | 17032 | |0;dojo.html;2549.2734570647367;2559.846409215446;2577.368544706547;2588.430545280039;2608.2696844883426;4610.4467463904375;4748.5488878556525;4752.500987396325;4787.6169906407495;4791.688540244886;4658.673892827504;4661.347411362403;4712.293156026589;4730.172219861528;4738.28925661151;4307.849225277116;4332.068605249879;4397.604215620593;4401;4453.955460445402;4145;4473.4855491618555;4495.685302028859;4504.279315309551;4555.043440877404;4248.596383343608;4281.807318670667;4451.2655411857;4490.461144662645;4503.617192538638;4328.935065974013;4335.56644335567;4337.652987760972;4345.065810850682;4347.500037495697;4310.31035034393;4318.352247162919;4324.329728892027;4334.393184954109;4338.544452832439;4625.074985002979;4647.34937108805;4649.837255696035;4653.697509661895;4670.556297151765;4911.705297682151;4954.835613630794;4956.752162391902;4961.677490963112;4981.90036199274;5013.674111182761;5026.8240611578785;5028.220625803006;5032.7735251913755;5034.01836641855;4694.577488026094;4698.083873644675;4712.458067322234;4716.52834716529;4717;5106.055379754757;5141.434442211428;5190.532852043334;5212.478752124757;5236.319278493771;4976.402831660224;4987.775550100254;5002.074616196015;5003.2745251938995;5007.048660754445;4824.396950381202;4888.975555122272;4953.108440480748;4956.727379994063;4991.4758950310425;4981.32752078472;4982.7010379376625;4989.052080104769;5000.74996250186;5023.6985780853265;4990.775415106329;4999.625028122905;4999.750012499433;5001.124803159432;5040.31955685985;4190.937135942902;4219.198352313051;4220.198162349206;4283.935740963888;4354.673399495051;3708.4808126862263;3723.832427540767;3744.5319335083113;3746.4193050077283;3765.7175711821174;4395.692301538862;4433.091216300651;4434.957784920541;4459.264221403497;4477;5119.334486516804;5126.92309615356;5127.2052831811125;5129.12804823181;5177.611679124081;4239.915201695949;4312.935305970413;4431.379606855043;4446.911061778746;4475.48531918827;4579.977100114545;4601.33280674307;4613.331066995334;4632.444106707217;4673.859784206479;4753.881152971065;4754;4754.524547545287;4757.976210118927;4764.142454358249;5055.16589762685;5096.490350964874;5114.590832733446;5122.052420302256;5173.198154286091;4587.655925805511;4620.306953956933;4630.305454181899;4696.084263568571;4701.741404222664;4992.975035124947;5020.598352131894;5048.495150484922;5073.543381095719;5136.229565565195;5144.485551444825;5145.305383773143;5149.639525233198;5153.561947234377;5194.168932970707;4740.407449068866;4984.576311013604;4985.401751789664;4995.500449954975;5001.42483614389;4779.426468823764;4870.147724148189;4896.6572339935865;4926.802927882988;4996.250562415521;4838.298446725134;4845.030993801183;4852.369192005158;4862.4651288357545;4877.95122048775;4777.092352453023;4978.029284289673;4980.975095124501;4991.775370108281;5006.924896126634;4651.441826980784;4816.133096042606;4841.903161936812;4847.079054979543;4909.533594308535 06:14:19 INFO - PROCESS | 17032 | |1;ext.html;520.448324775738;541.2557733116964;547.4580165636019;547.8301726464799;552.8086972574101;38606;39934;39988.000299992476;43101.56898431015;43629;40634.18731625366;41025;41035.58964410355;41051;41073.58926410735;7649.273319034697;7780.533168009909;7816.17930117338;7835.255650713173;8947.731568052968;9384.483853388061;9419.48192849393;9468.384555003902;9469.573869175869;9478.28912831537;9415.670451534197;9462.432254064779;9472.952635236814;9485.620575176985;9488;9334.486603236819;9355.111264429868;9382.437053776795;9410.341276110641;9418.387804792666;9327.20718738908;9343.626254949793;9418.481983490905;9420.434773913586;9444.48055356955;33432.49851252233;33560.83219583911;33572.49641255383;33573.83213083944;33728.32543349144;3359.344927739092;3363.865445382182;3400.880969166092;3453.9309213815836;3473.0796338970194;22828.31515054551;24770;24780.25659230208;24841;24948.3762905927;3332.2502436951736;3536.5756109266795;3648.945265821015;3685.649863262986;3700.389435743099;234550;235539;235692;235932;236925;37697.81151094227;38979.805100974314;45399.31901021487;45689.31466028012;45804.541954580694;45491.31763023557;45789;46507.76746116315;46621.30068048982;46632.300515492294;13268.601941941757;13416.530421435204;13423.328833558478;13443.529476468277;13450.529231477047;10188.643397481053;10199.235057370726;10203.081722644998;10259.487025648688;10275.794484110276;15606.531804045897;15616.4534241301;15727.68544629101;15743.606409839846;15989.440369587008;6609.504287178481;6616.536842421081;6753.3246675332075;6773.119494465688;6891.448684105259;744.3635691483703;746.5707218349362;759.2786852490046;772.1930582541257;774.1368374262656;25123.35711321848;26469.47061058768;26812;27139.728602714116;27196.45607087847;888.7333799860016;891.1088911088912;892.2683399612256;895.6148855991855;910.499225426013;13668.453261869414;13815.51645692416;14096.929515352358;14295.213763243122;14295.57113286603;315939;317900;317998;318202;319353;47921.76039119782;47929;48406;48675.026499469815;48701.512984869696;1055.7255113670346;1113.6826004588654;1168.2289688805179;1168.9415529223504;1170.0639488409238;24265.729034188815;24920.62619060752;25948.35129121787;26234.86882565575;26248.212553623423;40446.59553404546;40624.59375406208;40665.39001915033;40666.79666601648;40677.389839151845;256917;257813;257869;258774;258852;23146.76853231446;24027.27918162458;24037;24065.759342406353;24963;35266.47100293549;35528.289434211685;35657.1085722859;36004;36470;30266.84866575653;30403.695963040973;30818.691813082478;31058.378832423678;31754.682453175174;29758.25604359908;30136.698633014264;30171;30332.545011824397;31073.68926310708;29802.254943626584;30235;30416.239594010327;30473.390532189675;30481;26573.202803915912;27557.586636200875;27640;27993;28439.857800710863;21407.143714251255;21933.451663708536;22040.33878983633;22068.33794986153;22162.44593885166 06:14:19 INFO - PROCESS | 17032 | |2;jquery.html;372.98508059677613;388.93971434427675;396.7639254643488;403.4210907347956;420.3747596261346;16403.589910252234;17761.91119044406;17877.642447151065;17883.374081907135;18297.35959241427;17287.30850765968;17380.47858564245;17609.735853962167;17729.202185901635;17748.378806741766;5184.35195600549;5389.191621256823;5391.946080539204;5396.43337449568;5404.378496472897;5409;5661.320641523023;5687.26065611472;5758.7408566614395;5762;5734.942650573483;5740.942590574083;5742.224799652056;5744.22452968848;5751.82744517663;6187.102870083858;6226.87546249077;6235.625862448268;6241.687915604201;6340.904886426707;5462.770876552783;5598.58010649203;5613.186088017214;5635.6618602883955;5644.379118296984;21189.364319070453;21325.36023919285;21430.678539821914;21488.462788430257;21583.892080539656;1770.9203085861038;2351.2828587280874;2482.3049546127113;2510.7740303372784;2587.1462417402213;8827.16141966512;9212.953935230347;9445.811083778355;9626.663066792698;9847.310688251826;1703.9744403833952;1744.2412550540555;1753.0708724376102;1755.613764971704;1767.7083281258576;63546;65124.023139653415;74815.25184748137;74933.25066749372;76105.61947190229;10697.679069627768;10705.411202383944;10787.78424431507;11062.612808551663;11168.66494005181;15252.847471525365;15332.923335383251;15351.539453816404;15390.769138462932;15448.459303924308;6665.766698165541;6825.385715285648;6833.043373927555;7119.359257666835;7154.284571542804;6232.937670623326;6281.748730050746;6453.548251622341;6462.353764623594;6486.059521369375;4600.746958917225;4613.492515823256;4687.10944920464;4788.090262850117;4806.2310030395665;1952.6526696579315;1971.6253911756721;2005.4084045206698;2018.1826360324092;2035.450428384328;741.7931653331403;747.6598147842718;750.8723517002122;770.445279398832;796.6614188969664;16952.23714932831;17213.13934303305;17863.821361786475;17907.820921790877;18116.275348986157;833.1676277590075;878.7934835313696;880.9867851982225;885.3448448148324;889.217488610032;9601.519924003773;9637.277204209713;9926.404415735078;9929.219718577197;10053.095221430107;456023;457404;479942;480949;483292;67656.66171669209;85232.14767852242;89458.55270723604;92440.30238790507;93329;973.9853902191472;980.2011360740973;989.7080361293436;995.1738559742809;996.8454889492285;14490.85509144895;14892.702145957237;15534.1456219909;15546.611334716723;15739.606509837346;34451.310973780885;39562.604373957045;40406.1918761629;41077.589224107374;41571.37642935419;52349;52611.47388526219;53035;53061.46938530565;56118.43881561296;7523.398128149625;7585;7605.88591171144;7663.386929045551;8252.956051534584;10106.949465252626;11606.941965290458;11974.101942354184;12432.875671243171;12672.493100275886;9775;10038;10140.340877842917;10249.33379330371;10894.291871028357;10248.180145588483;10735.302205356627;10795.730106747395;10836.403997780199;10847.620333288298;10721.678349649523;10761.139108871115;10854.565817367533;10892.237543372205;11354.772904541698;7684.193159718149;7930.325922296669;7967.163447838126;8107.918920810716;8235.547044912588;10279.588816447258;10436.843447348145;10454.581816727246;10481.37111773296;11088.223824332219 06:14:19 INFO - PROCESS | 17032 | |3;mootools.html;1282.5767496726082;1302.5375991523008;1305.425612730399;1308.110484870288;1313.1530163044833;7028.789136325906;7054.329838665331;7305.926940730591;7479.850402991937;7499.250074992505;7382;7486.064241969754;7490.3633191178815;7506;7506.211847755989;2076.6054449654557;2092.4350425385137;2101.8003289687454;2111.6515774897202;2116.947076323089;2080.3589638996573;2096.433962830035;2106.8314534837255;2113.144176608476;2115.7778433264516;2073.481629592602;2077.3041031254547;2096.077725800643;2097.3183715292516;2104.4633618427356;2047.7542694876636;2069.6585063464586;2071.078370125295;2072.8030837070455;2073.067119796098;1758.753774471575;1801.7297405389165;2040.0615716770303;2056.8148866601928;2119.597276517472;7078.398336141434;7295.854082918365;7311.853762924712;7391.22392148822;7422.294881986203;4453.643708503312;4598.172328980782;4601.3558101865765;4604.746738929358;4612.677112602119;2720.1567514070503;2840.5455127179757;2881.7694584433193;2903.9274018149504;2905.3608206194604;1042.4943902207424;1044.185535282481;1048.0829274384914;1072.6031368393744;1089.0797276301512;9843.655472058443;9852.852207216898;9933.801323973552;9999.600015999424;10009.099181073665;3760.52993375828;3811.9809400952818;3938.6652134568308;3942.2903877301787;4104.199681062164;7717.536947783151;7728.497647652885;7749.8450030999065;7765.107012693499;7775.494592851446;4494.573015563582;4507.6168525675685;4529.728216307032;4541.114482675913;4550.294704320836;4042.535108462506;4204.348326009474;4255.148970205909;4309.633681137138;4317.84887528935;3123.7969531980802;3172.1435212492497;3279.8032118072992;3283.244853683666;3285.7535684823733;955.1690029674228;958.5063692198523;967.4147140979746;968.3463662028103;970.7281961050917;731.7146312937958;745.8023623739713;755.5089192025227;766.9769906902802;781.7882282462275;4502.549745025537;4533.750643714561;4617.8845528861375;4648.907021859612;4692.601128904013;498.63599572312415;849.1550906847726;861.3539845116163;870.3211495033884;881.2289246908955;2970.702929707012;3049.9390012199633;3068.601081859344;3086.3210093779335;3255.918602034968;8288.668453261922;8628.568571571397;8646.308295336357;8662.480251184948;8863.468191908507;8723.563821808884;8796.692115776044;8806.119388061143;8810.691625793066;8815.955920220485;6805.421539169127;6895.896561551581;6896.310368963063;6968.128983877015;6975.232724400312;4529.8641040768825;4630.907381852411;4821.710697358169;4897.118518666674;5041.8487445376695;7905.130435651959;7922.960385198037;7997.520148791091;8034.558099304594;8082.393820463371;8052.919470805217;8069.878951815845;8180.263776260165;8187.71343002992;8253.422260441952;6310.905336420049;6374.394432528902;6395.360463953567;6433.292337842927;6448.387403196689;6854.520183587101;6887.586744795329;6939.618320992192;6952.165740111219;6964.930350696428;6270.341614130451;6367.649779262185;6378.649174295273;6437.903431448625;6557.901631475627;6305.5901366411035;6369.903329110039;6371.267304260069;6535.869282614226;6536.477081833346;5342.813001544927;5424.83725488236;5439.5920305978;5445;5452.400235973961;3786.1481166737262;3814.218085292564;3929.115948911586;3956.465877106669;3996.480457540501;3944.7041471889147;3993.740406873544;4071.653909417614;4086.40747091676;4220.810063547209 06:14:19 INFO - PROCESS | 17032 | |4;prototype.html;352.1161883671984;357.4227622389841;365.33873688623595;365.4791921511845;369.62298455575313;4954.925676114856;4981.402231732188;4990.800367985281;5007.49925007499;5015.439038282814;4962.826301079463;4976.626752993531;4978.103941290576;4982.701037937717;4989.900201995958;3911.804409779507;4032.9596704033024;4062.2078694654556;4066.79666016698;4071.1654110907343;4069.2675318442816;4077.67378609712;4080.489938757655;4086.4483294755273;4115.32097203961;4079.5920407959115;4082.4080508326274;4095.4915638466214;4095.5904409558952;4096.897577560555;3948.6446219840354;4012.378081397388;4037.1723796621623;4071.857484988026;4072.1041370898356;3649.124210189562;3650.8539658413633;3655.4151335786146;3663.175785448253;3665.358562251622;4827.686200396998;4853.466118726938;4857.781399837016;4860.8091017700535;4861.73260470674;4491.865244042684;4564.360989461478;4570.862874113782;4598.4711758147905;4610.962533429985;5222.477752224785;5244.632875698702;5257.5005374489365;5258.50044245796;5265;3949.4273330366937;3972.2254160438724;3974.3442332015184;3974.5230572331216;3986.362182050858;4881.145799485112;5536.252605898192;5559.332880054379;5582.134769110754;5590.6582420219265;4002.219567184371;4011.338129208706;4023.4970628671417;4029.6977726670616;4035.53591337;4106.609872062149;4111.239420707178;4120.711550191458;4131.5248746393945;4143.274926887782;4566.452025756864;4568.2690769477385;4572.451305843254;4586.495485496593;4589.242774942164;5075.259359945662;5231.843044708665;5247.248217524489;5257.395399529027;5263.421023687391;1362.7206422683325;1366.5763613279914;1370.205280937054;1372.6774208061097;1373.230990645242;932.8265142218614;983.8868530119128;991.2763682511807;993.5280741647804;996.4121168510613;4442.489113751895;4453.799579018887;4467.798949047301;4490.932636010462;4497.775111244425;5225.99505284463;5233.336664334981;5250.634834942943;5253.763580638881;5257.316548848626;4329.9134017319475;4481.619062379669;4485.282354823213;4490.191765482179;4492.910141797146;2763.5440152375036;2773.586979327957;2786.8852459016152;2799.7620202283033;2810.8594570271403;4882.707037577757;4899.779509922062;4903.313536104948;4905;4910.754462276872;5143.434222235551;5211.941283193118;5354.32283858071;5369.463053694677;5458.0721277382145;5006.224035274538;5010.173321401928;5021.221710634857;5026.5621173730715;5046.798128074909;5349.277847490539;5351.839444816662;5383.250443605922;5389.541888939484;5402.810901618582;5143.4085080216255;5205.579405576425;5297.231901374356;5311.920321195263;5315.495027972336;4855.95144048555;4969.676970996874;4989.650724449254;5014.398272207359;5025.522575355335;3747.9625203747614;3809.1239015026144;3810.7713537187856;3822.0062783677;3827.3302172120325;5394.190871369169;5404.621676482766;5407.783688652567;5421.756020978986;5423.29497165366;4932.090066860569;5184;5236.816711415082;5239.842804715865;5272.947270527399;5179.896402071862;5185.896282074413;5187.792488300426;5188.273641690242;5190.870228244324;5124.923126153185;5125.769340379767;5150.94405646849;5156.1750119980625;5172.7413629319135;4986.8753281168265;5216.947830521646;5222.817201397933;5271.499207575274;5282.418933917189;5098.770555324945;5113.948860511347;5115.1559992600805;5141.845744627667;5143.61422893277 06:14:19 INFO - PROCESS | 17032 | |5;yui.html;668.7157957867905;670.7283550162186;724.9383802376798;728.1662496441571;762.157815613747;12383.504659813609;13297.800532992012;13304.800427993589;13304.933475332635;13339.466421343159;12456.19034762742;12506;13171.73656526869;13647.795283070762;13721.3825377858;753.3333000294724;800.9038915330154;818.0878320672438;818.6029775558849;819.4468733604793;796.1839114907209;812.4556547113418;812.8496228197773;813.7558732380292;815.6615004773012;844.4046946902449;848.1687945813106;854.5897968974896;855.5807654249404;856.092541905579;843.2158092973532;846.318713435684;846.7459762071385;847.639753104928;848.2111636178352;615.59986009094;623.5697368815503;623.6819222196713;624.3912185618985;624.5846512069469;13810.861891381159;13844.10013349129;14017.299135043208;14072.22602756848;14265.857341426557;12679;12975.805362919564;13000.674983125498;13195.142315749446;13425.530106446326;10475;10622.78754424915;10722.78554428907;10807.189460790472;10847.349159050482;3023.8639261233297;3041.893533726331;3085.3674996625637;3091.2426455518544;3105.4410206162884;35727.10682232939;44352.778236108934;57122;62520.37479625236;66515.3348466514;10391.168706503462;10451.216158788122;10490.31812932157;10632.521536530874;10751.78496430067;18420.35528756487;18594.81405185958;18597.34909278196;18801.435956921316;18819.90590047041;6529.706163222666;6560.540762146604;6594.043863639841;6691.59850408977;6833.111695479655;3602.189507360823;3602.531670882821;3635.8909232723063;3655.9634403656155;3671.7980511072146;2896.8846993907496;2993.9850300748794;3096.7987080839675;3258.885938992124;3262.9021129366156;584.6404461256296;586.4956137721556;594.174098003775;604.2235727090667;615.1418770814778;601.1493736362997;627.1502114634695;630.4105661206786;638.7975575387543;648.4974145037539;7814.57019863913;8769.298456123495;8809.647614095493;8831.823363532694;9014.86477702835;632.3441545508881;649.7108786589922;659.831742905556;662.718344703499;666.3602941176525;3970.6029397060643;4127.566605506378;4161.417401563784;4256.446661933991;4264.893377665583;49753.50246497561;51336;56168;62384.68807655932;67018.66490667516;56149.15776263359;57229.42770572324;57647.711761441766;58738.41261587415;58876.70561647164;51756.223656644426;52001.739991301314;52659.736701317015;52795.20807187895;53144.46855531395;8020.558869262013;8065.717699880476;8153.306968907709;8218.753437396888;8378.706745263886;34525;35688;36291.27417451689;36330.091747706516;36741.44887826738;42053.36919946264;45920.54079459162;46218.53781462142;46628.53371466242;46725.53274467212;10479.895201047892;10480.63317783874;10502.52738626779;10580.735481613021;10741.462926853783;11344.979689485685;11351.432428378714;11453.19827612059;11575.305481671126;12020.158588898694;10399.688009359732;10744.194185435967;10759.623413180501;10892.836607451052;11263.098952083978;9948.253880959102;10158.695239142837;10246.487675616338;10327.277090603886;10634;9742.805143897223;9830.75423114428;9909.355891867006;9928.305018648625;10028.29801913859;7852;7999.320057795153;8207.95896020516;8302.377321700777;8384.455010424303;7547.792353223677;7874.56689882062;7920;7965.203479651988;8102.432829701865 06:14:19 INFO - PROCESS | 17032 | __end_tp_report 06:14:19 INFO - PROCESS | 17032 | __start_cc_report 06:14:19 INFO - PROCESS | 17032 | _x_x_mozilla_cycle_collect,387 06:14:19 INFO - PROCESS | 17032 | __end_cc_report 06:14:19 INFO - PROCESS | 17032 | __startTimestamp1487686459871__endTimestamp 06:14:19 INFO - PROCESS | 17032 | 06:14:19 INFO - PROCESS | 17032 | ------- Summary: start ------- 06:14:19 INFO - PROCESS | 17032 | Number of tests: 6 06:14:19 INFO - PROCESS | 17032 | 06:14:19 INFO - PROCESS | 17032 | [#0] dojo.html Cycles:165 Average:4668.80 Median:4754.52 stddev:494.81 (10.4%) stddev-sans-first:467.56 06:14:19 INFO - PROCESS | 17032 | Values: 2549.3 2559.8 2577.4 2588.4 2608.3 4610.4 4748.5 4752.5 4787.6 4791.7 4658.7 4661.3 4712.3 4730.2 4738.3 4307.8 4332.1 4397.6 4401.0 4454.0 4145.0 4473.5 4495.7 4504.3 4555.0 4248.6 4281.8 4451.3 4490.5 4503.6 4328.9 4335.6 4337.7 4345.1 4347.5 4310.3 4318.4 4324.3 4334.4 4338.5 4625.1 4647.3 4649.8 4653.7 4670.6 4911.7 4954.8 4956.8 4961.7 4981.9 5013.7 5026.8 5028.2 5032.8 5034.0 4694.6 4698.1 4712.5 4716.5 4717.0 5106.1 5141.4 5190.5 5212.5 5236.3 4976.4 4987.8 5002.1 5003.3 5007.0 4824.4 4889.0 4953.1 4956.7 4991.5 4981.3 4982.7 4989.1 5000.7 5023.7 4990.8 4999.6 4999.8 5001.1 5040.3 4190.9 4219.2 4220.2 4283.9 4354.7 3708.5 3723.8 3744.5 3746.4 3765.7 4395.7 4433.1 4435.0 4459.3 4477.0 5119.3 5126.9 5127.2 5129.1 5177.6 4239.9 4312.9 4431.4 4446.9 4475.5 4580.0 4601.3 4613.3 4632.4 4673.9 4753.9 4754.0 4754.5 4758.0 4764.1 5055.2 5096.5 5114.6 5122.1 5173.2 4587.7 4620.3 4630.3 4696.1 4701.7 4993.0 5020.6 5048.5 5073.5 5136.2 5144.5 5145.3 5149.6 5153.6 5194.2 4740.4 4984.6 4985.4 4995.5 5001.4 4779.4 4870.1 4896.7 4926.8 4996.3 4838.3 4845.0 4852.4 4862.5 4878.0 4777.1 4978.0 4981.0 4991.8 5006.9 4651.4 4816.1 4841.9 4847.1 4909.5 06:14:19 INFO - PROCESS | 17032 | 06:14:19 INFO - PROCESS | 17032 | [#1] ext.html Cycles:180 Average:41627.17 Median:3361.61 stddev:71367.85 (2123.0%) stddev-sans-first:71501.31 06:14:19 INFO - PROCESS | 17032 | Values: 520.4 541.3 547.5 547.8 552.8 38606.0 39934.0 39988.0 43101.6 43629.0 40634.2 41025.0 41035.6 41051.0 41073.6 7649.3 7780.5 7816.2 7835.3 8947.7 9384.5 9419.5 9468.4 9469.6 9478.3 9415.7 9462.4 9473.0 9485.6 9488.0 9334.5 9355.1 9382.4 9410.3 9418.4 9327.2 9343.6 9418.5 9420.4 9444.5 33432.5 33560.8 33572.5 33573.8 33728.3 3359.3 3363.9 3400.9 3453.9 3473.1 22828.3 24770.0 24780.3 24841.0 24948.4 3332.3 3536.6 3648.9 3685.6 3700.4 234550.0 235539.0 235692.0 235932.0 236925.0 37697.8 38979.8 45399.3 45689.3 45804.5 45491.3 45789.0 46507.8 46621.3 46632.3 13268.6 13416.5 13423.3 13443.5 13450.5 10188.6 10199.2 10203.1 10259.5 10275.8 15606.5 15616.5 15727.7 15743.6 15989.4 6609.5 6616.5 6753.3 6773.1 6891.4 744.4 746.6 759.3 772.2 774.1 25123.4 26469.5 26812.0 27139.7 27196.5 888.7 891.1 892.3 895.6 910.5 13668.5 13815.5 14096.9 14295.2 14295.6 315939.0 317900.0 317998.0 318202.0 319353.0 47921.8 47929.0 48406.0 48675.0 48701.5 1055.7 1113.7 1168.2 1168.9 1170.1 24265.7 24920.6 25948.4 26234.9 26248.2 40446.6 40624.6 40665.4 40666.8 40677.4 256917.0 257813.0 257869.0 258774.0 258852.0 23146.8 24027.3 24037.0 24065.8 24963.0 35266.5 35528.3 35657.1 36004.0 36470.0 30266.8 30403.7 30818.7 31058.4 31754.7 29758.3 30136.7 30171.0 30332.5 31073.7 29802.3 30235.0 30416.2 30473.4 30481.0 26573.2 27557.6 27640.0 27993.0 28439.9 21407.1 21933.5 22040.3 22068.3 22162.4 06:14:19 INFO - PROCESS | 17032 | 06:14:19 INFO - PROCESS | 17032 | [#2] jquery.html Cycles:180 Average:27259.72 Median:242365.05 stddev:77637.12 (32.0%) stddev-sans-first:77828.66 06:14:19 INFO - PROCESS | 17032 | Values: 373.0 388.9 396.8 403.4 420.4 16403.6 17761.9 17877.6 17883.4 18297.4 17287.3 17380.5 17609.7 17729.2 17748.4 5184.4 5389.2 5391.9 5396.4 5404.4 5409.0 5661.3 5687.3 5758.7 5762.0 5734.9 5740.9 5742.2 5744.2 5751.8 6187.1 6226.9 6235.6 6241.7 6340.9 5462.8 5598.6 5613.2 5635.7 5644.4 21189.4 21325.4 21430.7 21488.5 21583.9 1770.9 2351.3 2482.3 2510.8 2587.1 8827.2 9213.0 9445.8 9626.7 9847.3 1704.0 1744.2 1753.1 1755.6 1767.7 63546.0 65124.0 74815.3 74933.3 76105.6 10697.7 10705.4 10787.8 11062.6 11168.7 15252.8 15332.9 15351.5 15390.8 15448.5 6665.8 6825.4 6833.0 7119.4 7154.3 6232.9 6281.7 6453.5 6462.4 6486.1 4600.7 4613.5 4687.1 4788.1 4806.2 1952.7 1971.6 2005.4 2018.2 2035.5 741.8 747.7 750.9 770.4 796.7 16952.2 17213.1 17863.8 17907.8 18116.3 833.2 878.8 881.0 885.3 889.2 9601.5 9637.3 9926.4 9929.2 10053.1 456023.0 457404.0 479942.0 480949.0 483292.0 67656.7 85232.1 89458.6 92440.3 93329.0 974.0 980.2 989.7 995.2 996.8 14490.9 14892.7 15534.1 15546.6 15739.6 34451.3 39562.6 40406.2 41077.6 41571.4 52349.0 52611.5 53035.0 53061.5 56118.4 7523.4 7585.0 7605.9 7663.4 8253.0 10106.9 11606.9 11974.1 12432.9 12672.5 9775.0 10038.0 10140.3 10249.3 10894.3 10248.2 10735.3 10795.7 10836.4 10847.6 10721.7 10761.1 10854.6 10892.2 11354.8 7684.2 7930.3 7967.2 8107.9 8235.5 10279.6 10436.8 10454.6 10481.4 11088.2 06:14:19 INFO - PROCESS | 17032 | 06:14:19 INFO - PROCESS | 17032 | [#3] mootools.html Cycles:180 Average:4740.84 Median:2770.24 stddev:2622.86 (94.7%) stddev-sans-first:2617.35 06:14:19 INFO - PROCESS | 17032 | Values: 1282.6 1302.5 1305.4 1308.1 1313.2 7028.8 7054.3 7305.9 7479.9 7499.3 7382.0 7486.1 7490.4 7506.0 7506.2 2076.6 2092.4 2101.8 2111.7 2116.9 2080.4 2096.4 2106.8 2113.1 2115.8 2073.5 2077.3 2096.1 2097.3 2104.5 2047.8 2069.7 2071.1 2072.8 2073.1 1758.8 1801.7 2040.1 2056.8 2119.6 7078.4 7295.9 7311.9 7391.2 7422.3 4453.6 4598.2 4601.4 4604.7 4612.7 2720.2 2840.5 2881.8 2903.9 2905.4 1042.5 1044.2 1048.1 1072.6 1089.1 9843.7 9852.9 9933.8 9999.6 10009.1 3760.5 3812.0 3938.7 3942.3 4104.2 7717.5 7728.5 7749.8 7765.1 7775.5 4494.6 4507.6 4529.7 4541.1 4550.3 4042.5 4204.3 4255.1 4309.6 4317.8 3123.8 3172.1 3279.8 3283.2 3285.8 955.2 958.5 967.4 968.3 970.7 731.7 745.8 755.5 767.0 781.8 4502.5 4533.8 4617.9 4648.9 4692.6 498.6 849.2 861.4 870.3 881.2 2970.7 3049.9 3068.6 3086.3 3255.9 8288.7 8628.6 8646.3 8662.5 8863.5 8723.6 8796.7 8806.1 8810.7 8816.0 6805.4 6895.9 6896.3 6968.1 6975.2 4529.9 4630.9 4821.7 4897.1 5041.8 7905.1 7923.0 7997.5 8034.6 8082.4 8052.9 8069.9 8180.3 8187.7 8253.4 6310.9 6374.4 6395.4 6433.3 6448.4 6854.5 6887.6 6939.6 6952.2 6964.9 6270.3 6367.6 6378.6 6437.9 6557.9 6305.6 6369.9 6371.3 6535.9 6536.5 5342.8 5424.8 5439.6 5445.0 5452.4 3786.1 3814.2 3929.1 3956.5 3996.5 3944.7 3993.7 4071.7 4086.4 4220.8 06:14:19 INFO - PROCESS | 17032 | 06:14:19 INFO - PROCESS | 17032 | [#4] prototype.html Cycles:180 Average:4372.25 Median:4907.88 stddev:1219.34 (24.8%) stddev-sans-first:1184.84 06:14:19 INFO - PROCESS | 17032 | Values: 352.1 357.4 365.3 365.5 369.6 4954.9 4981.4 4990.8 5007.5 5015.4 4962.8 4976.6 4978.1 4982.7 4989.9 3911.8 4033.0 4062.2 4066.8 4071.2 4069.3 4077.7 4080.5 4086.4 4115.3 4079.6 4082.4 4095.5 4095.6 4096.9 3948.6 4012.4 4037.2 4071.9 4072.1 3649.1 3650.9 3655.4 3663.2 3665.4 4827.7 4853.5 4857.8 4860.8 4861.7 4491.9 4564.4 4570.9 4598.5 4611.0 5222.5 5244.6 5257.5 5258.5 5265.0 3949.4 3972.2 3974.3 3974.5 3986.4 4881.1 5536.3 5559.3 5582.1 5590.7 4002.2 4011.3 4023.5 4029.7 4035.5 4106.6 4111.2 4120.7 4131.5 4143.3 4566.5 4568.3 4572.5 4586.5 4589.2 5075.3 5231.8 5247.2 5257.4 5263.4 1362.7 1366.6 1370.2 1372.7 1373.2 932.8 983.9 991.3 993.5 996.4 4442.5 4453.8 4467.8 4490.9 4497.8 5226.0 5233.3 5250.6 5253.8 5257.3 4329.9 4481.6 4485.3 4490.2 4492.9 2763.5 2773.6 2786.9 2799.8 2810.9 4882.7 4899.8 4903.3 4905.0 4910.8 5143.4 5211.9 5354.3 5369.5 5458.1 5006.2 5010.2 5021.2 5026.6 5046.8 5349.3 5351.8 5383.3 5389.5 5402.8 5143.4 5205.6 5297.2 5311.9 5315.5 4856.0 4969.7 4989.7 5014.4 5025.5 3748.0 3809.1 3810.8 3822.0 3827.3 5394.2 5404.6 5407.8 5421.8 5423.3 4932.1 5184.0 5236.8 5239.8 5272.9 5179.9 5185.9 5187.8 5188.3 5190.9 5124.9 5125.8 5150.9 5156.2 5172.7 4986.9 5216.9 5222.8 5271.5 5282.4 5098.8 5113.9 5115.2 5141.8 5143.6 06:14:19 INFO - PROCESS | 17032 | 06:14:19 INFO - PROCESS | 17032 | [#5] yui.html Cycles:180 Average:14122.66 Median:52330.74 stddev:17358.66 (33.2%) stddev-sans-first:17377.96 06:14:19 INFO - PROCESS | 17032 | Values: 668.7 670.7 724.9 728.2 762.2 12383.5 13297.8 13304.8 13304.9 13339.5 12456.2 12506.0 13171.7 13647.8 13721.4 753.3 800.9 818.1 818.6 819.4 796.2 812.5 812.8 813.8 815.7 844.4 848.2 854.6 855.6 856.1 843.2 846.3 846.7 847.6 848.2 615.6 623.6 623.7 624.4 624.6 13810.9 13844.1 14017.3 14072.2 14265.9 12679.0 12975.8 13000.7 13195.1 13425.5 10475.0 10622.8 10722.8 10807.2 10847.3 3023.9 3041.9 3085.4 3091.2 3105.4 35727.1 44352.8 57122.0 62520.4 66515.3 10391.2 10451.2 10490.3 10632.5 10751.8 18420.4 18594.8 18597.3 18801.4 18819.9 6529.7 6560.5 6594.0 6691.6 6833.1 3602.2 3602.5 3635.9 3656.0 3671.8 2896.9 2994.0 3096.8 3258.9 3262.9 584.6 586.5 594.2 604.2 615.1 601.1 627.2 630.4 638.8 648.5 7814.6 8769.3 8809.6 8831.8 9014.9 632.3 649.7 659.8 662.7 666.4 3970.6 4127.6 4161.4 4256.4 4264.9 49753.5 51336.0 56168.0 62384.7 67018.7 56149.2 57229.4 57647.7 58738.4 58876.7 51756.2 52001.7 52659.7 52795.2 53144.5 8020.6 8065.7 8153.3 8218.8 8378.7 34525.0 35688.0 36291.3 36330.1 36741.4 42053.4 45920.5 46218.5 46628.5 46725.5 10479.9 10480.6 10502.5 10580.7 10741.5 11345.0 11351.4 11453.2 11575.3 12020.2 10399.7 10744.2 10759.6 10892.8 11263.1 9948.3 10158.7 10246.5 10327.3 10634.0 9742.8 9830.8 9909.4 9928.3 10028.3 7852.0 7999.3 8208.0 8302.4 8384.5 7547.8 7874.6 7920.0 7965.2 8102.4 06:14:19 INFO - PROCESS | 17032 | -------- Summary: end -------- 06:14:19 INFO - PROCESS | 17032 | 06:14:20 INFO - TEST-INFO | 17032: exit 0 06:14:20 INFO - TEST-OK | dromaeo_css | took 1122095ms 06:14:20 INFO - TEST-START | kraken 06:14:20 INFO - operating with platform_type : linux_ 06:14:20 INFO - Initialising browser for kraken test... 06:14:20 INFO - TEST-INFO | started process 17411 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuANYsm/profile http://localhost:42357/getInfo.html) 06:14:24 INFO - PROCESS | 17411 | Iterating over profile /tmp/tmpuANYsm/profile 06:14:24 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:24 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/addons.json = Tue Feb 21 2017 06:14:23 GMT-0800 (PST) 06:14:25 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:25 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/extensions.json = Tue Feb 21 2017 06:14:23 GMT-0800 (PST) 06:14:25 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:25 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/places.sqlite-wal = Tue Feb 21 2017 06:14:25 GMT-0800 (PST) 06:14:25 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:25 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/key3.db = Tue Feb 21 2017 06:14:21 GMT-0800 (PST) 06:14:25 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:25 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/permissions.sqlite = Tue Feb 21 2017 06:14:22 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/localstore.rdf = Tue Feb 21 2017 05:54:55 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/startupCache = Tue Feb 21 2017 06:14:20 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | __metrics Screen width/height:1600/1200 06:14:26 INFO - PROCESS | 17411 | colorDepth:24 06:14:26 INFO - PROCESS | 17411 | Browser inner width/height: 1024/697 06:14:26 INFO - PROCESS | 17411 | __metrics 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/bookmarkbackups = Tue Feb 21 2017 06:14:23 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/thumbnails = Tue Feb 21 2017 06:14:23 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/cache2 = Tue Feb 21 2017 06:14:21 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/crashes = Tue Feb 21 2017 06:14:20 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/mimeTypes.rdf = Tue Feb 21 2017 06:14:24 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/prefs.js = Tue Feb 21 2017 05:54:55 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/places.sqlite-shm = Tue Feb 21 2017 06:14:22 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/pluginreg.dat = Tue Feb 21 2017 06:14:23 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/.parentlock = Tue Feb 21 2017 06:14:20 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/blocklist.xml = Tue Feb 21 2017 06:14:21 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/webappsstore.sqlite = Tue Feb 21 2017 06:14:25 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/safebrowsing = Tue Feb 21 2017 06:14:26 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/extensions = Tue Feb 21 2017 06:14:21 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/frequencyCap.json = Tue Feb 21 2017 06:14:23 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/sessionCheckpoints.json = Tue Feb 21 2017 06:14:24 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/compatibility.ini = Tue Feb 21 2017 06:14:21 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | Stat failure 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/cert8.db = Tue Feb 21 2017 06:14:21 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/user.js = Tue Feb 21 2017 06:14:20 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/extensions.ini = Tue Feb 21 2017 06:14:21 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/content-prefs.sqlite = Tue Feb 21 2017 06:14:23 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/directoryLinks.json = Tue Feb 21 2017 06:14:23 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/secmod.db = Tue Feb 21 2017 06:14:21 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/gmp = Tue Feb 21 2017 06:14:21 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/minidumps = Tue Feb 21 2017 06:14:20 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | No birth date. Using mtime. 06:14:26 INFO - PROCESS | 17411 | Using date: /tmp/tmpuANYsm/profile/places.sqlite = Tue Feb 21 2017 06:14:25 GMT-0800 (PST) 06:14:26 INFO - PROCESS | 17411 | Stat failure 06:14:27 ERROR - PROCESS | 17411 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 06:14:28 INFO - TEST-INFO | 17411: exit 0 06:14:28 INFO - Browser initialized. 06:14:28 INFO - Running cycle 1/1 for kraken test... 06:14:28 INFO - TEST-INFO | started process 17541 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuANYsm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:14:40 INFO - PROCESS | 17541 | RSS: Main: 153776128 06:14:40 INFO - PROCESS | 17541 | 06:14:42 INFO - PROCESS | 17541 | Cycle 1(1): loaded http://localhost:42357/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42357/tests/kraken/driver.html?testName=audio-beat-detection) 06:14:43 INFO - PROCESS | 17541 | RSS: Main: 160014336 06:14:43 INFO - PROCESS | 17541 | 06:14:46 INFO - PROCESS | 17541 | Cycle 1(1): loaded http://localhost:42357/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42357/tests/kraken/driver.html?testName=audio-dft) 06:14:47 INFO - PROCESS | 17541 | RSS: Main: 158838784 06:14:47 INFO - PROCESS | 17541 | 06:14:50 INFO - PROCESS | 17541 | Cycle 1(1): loaded http://localhost:42357/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42357/tests/kraken/driver.html?testName=audio-fft) 06:14:50 INFO - PROCESS | 17541 | RSS: Main: 155590656 06:14:50 INFO - PROCESS | 17541 | 06:14:53 INFO - PROCESS | 17541 | Cycle 1(1): loaded http://localhost:42357/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42357/tests/kraken/driver.html?testName=audio-oscillator) 06:14:53 INFO - PROCESS | 17541 | RSS: Main: 155840512 06:14:53 INFO - PROCESS | 17541 | 06:14:56 INFO - PROCESS | 17541 | Cycle 1(1): loaded http://localhost:42357/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42357/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:14:56 INFO - PROCESS | 17541 | RSS: Main: 156553216 06:14:56 INFO - PROCESS | 17541 | 06:14:59 INFO - PROCESS | 17541 | Cycle 1(1): loaded http://localhost:42357/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42357/tests/kraken/driver.html?testName=imaging-darkroom) 06:15:00 INFO - PROCESS | 17541 | RSS: Main: 161579008 06:15:00 INFO - PROCESS | 17541 | 06:15:03 INFO - PROCESS | 17541 | Cycle 1(1): loaded http://localhost:42357/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42357/tests/kraken/driver.html?testName=imaging-desaturate) 06:15:04 INFO - PROCESS | 17541 | RSS: Main: 157540352 06:15:04 INFO - PROCESS | 17541 | 06:15:07 INFO - PROCESS | 17541 | Cycle 1(1): loaded http://localhost:42357/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42357/tests/kraken/driver.html?testName=json-parse-financial) 06:15:08 INFO - PROCESS | 17541 | RSS: Main: 158658560 06:15:08 INFO - PROCESS | 17541 | 06:15:09 INFO - PROCESS | 17541 | Cycle 1(1): loaded http://localhost:42357/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42357/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:15:10 INFO - PROCESS | 17541 | RSS: Main: 159137792 06:15:10 INFO - PROCESS | 17541 | 06:15:12 INFO - PROCESS | 17541 | Cycle 1(1): loaded http://localhost:42357/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42357/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:15:13 INFO - PROCESS | 17541 | RSS: Main: 159174656 06:15:13 INFO - PROCESS | 17541 | 06:15:15 INFO - PROCESS | 17541 | Cycle 1(1): loaded http://localhost:42357/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42357/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:15:16 INFO - PROCESS | 17541 | RSS: Main: 159293440 06:15:16 INFO - PROCESS | 17541 | 06:15:18 INFO - PROCESS | 17541 | Cycle 1(1): loaded http://localhost:42357/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42357/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:15:19 INFO - PROCESS | 17541 | RSS: Main: 159469568 06:15:19 INFO - PROCESS | 17541 | 06:15:22 INFO - PROCESS | 17541 | Cycle 1(1): loaded http://localhost:42357/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42357/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:15:23 INFO - PROCESS | 17541 | RSS: Main: 159252480 06:15:23 INFO - PROCESS | 17541 | 06:15:25 INFO - PROCESS | 17541 | Cycle 1(1): loaded http://localhost:42357/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42357/tests/kraken/driver.html?testName=ai-astar) 06:15:25 INFO - PROCESS | 17541 | RSS: Main: 160808960 06:15:25 INFO - PROCESS | 17541 | 06:15:25 INFO - PROCESS | 17541 | __start_tp_report 06:15:25 INFO - PROCESS | 17541 | _x_x_mozilla_page_load 06:15:25 INFO - PROCESS | 17541 | _x_x_mozilla_page_load_details 06:15:25 INFO - PROCESS | 17541 | |i|pagename|runs| 06:15:25 INFO - PROCESS | 17541 | |0;ai-astar;109;117;117;115;116;117;116;115;115;114 06:15:25 INFO - PROCESS | 17541 | |1;audio-beat-detection;158;128;130;177;132;134;139;135;160;131 06:15:25 INFO - PROCESS | 17541 | |2;audio-dft;164;164;168;170;173;165;167;167;164;165 06:15:25 INFO - PROCESS | 17541 | |3;audio-fft;77;77;77;121;78;77;77;77;111;77 06:15:25 INFO - PROCESS | 17541 | |4;audio-oscillator;86;84;113;84;85;84;97;84;84;112 06:15:25 INFO - PROCESS | 17541 | |5;imaging-gaussian-blur;117;114;114;114;114;114;114;114;114;115 06:15:25 INFO - PROCESS | 17541 | |6;imaging-darkroom;150;152;151;150;151;152;150;151;150;151 06:15:25 INFO - PROCESS | 17541 | |7;imaging-desaturate;96;96;96;95;96;97;96;96;97;97 06:15:25 INFO - PROCESS | 17541 | |8;json-parse-financial;67;68;67;69;70;72;70;70;69;71 06:15:25 INFO - PROCESS | 17541 | |9;json-stringify-tinderbox;64;65;64;65;66;65;66;66;65;66 06:15:25 INFO - PROCESS | 17541 | |10;stanford-crypto-aes;88;97;96;92;87;98;87;88;87;91 06:15:25 INFO - PROCESS | 17541 | |11;stanford-crypto-ccm;157;138;144;136;138;149;144;142;182;158 06:15:25 INFO - PROCESS | 17541 | |12;stanford-crypto-pbkdf2;183;194;187;185;188;188;192;187;186;185 06:15:25 INFO - PROCESS | 17541 | |13;stanford-crypto-sha256-iterative;78;72;72;72;70;76;71;70;76;70 06:15:25 INFO - PROCESS | 17541 | __end_tp_report 06:15:25 INFO - PROCESS | 17541 | __start_cc_report 06:15:25 INFO - PROCESS | 17541 | _x_x_mozilla_cycle_collect,879 06:15:25 INFO - PROCESS | 17541 | __end_cc_report 06:15:25 INFO - PROCESS | 17541 | __startTimestamp1487686525514__endTimestamp 06:15:25 INFO - PROCESS | 17541 | 06:15:25 INFO - PROCESS | 17541 | ------- Summary: start ------- 06:15:25 INFO - PROCESS | 17541 | Number of tests: 14 06:15:25 INFO - PROCESS | 17541 | 06:15:25 INFO - PROCESS | 17541 | [#0] ai-astar Cycles:10 Average:115.10 Median:116.00 stddev:2.38 (2.1%) stddev-sans-first:1.09 06:15:25 INFO - PROCESS | 17541 | Values: 109.0 117.0 117.0 115.0 116.0 117.0 116.0 115.0 115.0 114.0 06:15:25 INFO - PROCESS | 17541 | 06:15:25 INFO - PROCESS | 17541 | [#1] audio-beat-detection Cycles:10 Average:142.40 Median:137.00 stddev:16.62 (12.1%) stddev-sans-first:16.64 06:15:25 INFO - PROCESS | 17541 | Values: 158.0 128.0 130.0 177.0 132.0 134.0 139.0 135.0 160.0 131.0 06:15:25 INFO - PROCESS | 17541 | 06:15:25 INFO - PROCESS | 17541 | [#2] audio-dft Cycles:10 Average:166.70 Median:167.00 stddev:2.98 (1.8%) stddev-sans-first:3.00 06:15:25 INFO - PROCESS | 17541 | Values: 164.0 164.0 168.0 170.0 173.0 165.0 167.0 167.0 164.0 165.0 06:15:25 INFO - PROCESS | 17541 | 06:15:25 INFO - PROCESS | 17541 | [#3] audio-fft Cycles:10 Average:84.90 Median:77.00 stddev:16.56 (21.5%) stddev-sans-first:17.32 06:15:25 INFO - PROCESS | 17541 | Values: 77.0 77.0 77.0 121.0 78.0 77.0 77.0 77.0 111.0 77.0 06:15:25 INFO - PROCESS | 17541 | 06:15:25 INFO - PROCESS | 17541 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:84.00 stddev:11.86 (14.1%) stddev-sans-first:12.42 06:15:25 INFO - PROCESS | 17541 | Values: 86.0 84.0 113.0 84.0 85.0 84.0 97.0 84.0 84.0 112.0 06:15:25 INFO - PROCESS | 17541 | 06:15:25 INFO - PROCESS | 17541 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.00 stddev:0.97 (0.8%) stddev-sans-first:0.33 06:15:25 INFO - PROCESS | 17541 | Values: 117.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 115.0 06:15:25 INFO - PROCESS | 17541 | 06:15:25 INFO - PROCESS | 17541 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:151.00 stddev:0.79 (0.5%) stddev-sans-first:0.78 06:15:25 INFO - PROCESS | 17541 | Values: 150.0 152.0 151.0 150.0 151.0 152.0 150.0 151.0 150.0 151.0 06:15:25 INFO - PROCESS | 17541 | 06:15:25 INFO - PROCESS | 17541 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 06:15:25 INFO - PROCESS | 17541 | Values: 96.0 96.0 96.0 95.0 96.0 97.0 96.0 96.0 97.0 97.0 06:15:25 INFO - PROCESS | 17541 | 06:15:25 INFO - PROCESS | 17541 | [#8] json-parse-financial Cycles:10 Average:69.30 Median:70.00 stddev:1.64 (2.3%) stddev-sans-first:1.51 06:15:25 INFO - PROCESS | 17541 | Values: 67.0 68.0 67.0 69.0 70.0 72.0 70.0 70.0 69.0 71.0 06:15:25 INFO - PROCESS | 17541 | 06:15:25 INFO - PROCESS | 17541 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.50 stddev:0.79 (1.2%) stddev-sans-first:0.71 06:15:25 INFO - PROCESS | 17541 | Values: 64.0 65.0 64.0 65.0 66.0 65.0 66.0 66.0 65.0 66.0 06:15:25 INFO - PROCESS | 17541 | 06:15:25 INFO - PROCESS | 17541 | [#10] stanford-crypto-aes Cycles:10 Average:91.10 Median:91.50 stddev:4.43 (4.8%) stddev-sans-first:4.56 06:15:25 INFO - PROCESS | 17541 | Values: 88.0 97.0 96.0 92.0 87.0 98.0 87.0 88.0 87.0 91.0 06:15:25 INFO - PROCESS | 17541 | 06:15:25 INFO - PROCESS | 17541 | [#11] stanford-crypto-ccm Cycles:10 Average:148.80 Median:146.50 stddev:13.92 (9.5%) stddev-sans-first:14.44 06:15:25 INFO - PROCESS | 17541 | Values: 157.0 138.0 144.0 136.0 138.0 149.0 144.0 142.0 182.0 158.0 06:15:25 INFO - PROCESS | 17541 | 06:15:25 INFO - PROCESS | 17541 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.50 Median:187.50 stddev:3.31 (1.8%) stddev-sans-first:3.08 06:15:25 INFO - PROCESS | 17541 | Values: 183.0 194.0 187.0 185.0 188.0 188.0 192.0 187.0 186.0 185.0 06:15:25 INFO - PROCESS | 17541 | 06:15:25 INFO - PROCESS | 17541 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.70 Median:72.00 stddev:2.91 (4.0%) stddev-sans-first:2.37 06:15:25 INFO - PROCESS | 17541 | Values: 78.0 72.0 72.0 72.0 70.0 76.0 71.0 70.0 76.0 70.0 06:15:25 INFO - PROCESS | 17541 | -------- Summary: end -------- 06:15:25 INFO - PROCESS | 17541 | 06:15:26 INFO - TEST-INFO | 17541: exit 0 06:15:26 INFO - TEST-OK | kraken | took 65892ms 06:15:26 INFO - SUITE-END | took 1187s 06:15:27 INFO - Completed test suite (00:19:48) 06:15:27 INFO - javascript benchmark 06:15:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6295.997699123877, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2549.2734570647367, 2559.846409215446, 2577.368544706547, 2588.430545280039, 2608.2696844883426, 4610.4467463904375, 4748.5488878556525, 4752.500987396325, 4787.6169906407495, 4791.688540244886, 4658.673892827504, 4661.347411362403, 4712.293156026589, 4730.172219861528, 4738.28925661151, 4307.849225277116, 4332.068605249879, 4397.604215620593, 4401.0, 4453.955460445402, 4145.0, 4473.4855491618555, 4495.685302028859, 4504.279315309551, 4555.043440877404, 4248.596383343608, 4281.807318670667, 4451.2655411857, 4490.461144662645, 4503.617192538638, 4328.935065974013, 4335.56644335567, 4337.652987760972, 4345.065810850682, 4347.500037495697, 4310.31035034393, 4318.352247162919, 4324.329728892027, 4334.393184954109, 4338.544452832439, 4625.074985002979, 4647.34937108805, 4649.837255696035, 4653.697509661895, 4670.556297151765, 4911.705297682151, 4954.835613630794, 4956.752162391902, 4961.677490963112, 4981.90036199274, 5013.674111182761, 5026.8240611578785, 5028.220625803006, 5032.7735251913755, 5034.01836641855, 4694.577488026094, 4698.083873644675, 4712.458067322234, 4716.52834716529, 4717.0, 5106.055379754757, 5141.434442211428, 5190.532852043334, 5212.478752124757, 5236.319278493771, 4976.402831660224, 4987.775550100254, 5002.074616196015, 5003.2745251938995, 5007.048660754445, 4824.396950381202, 4888.975555122272, 4953.108440480748, 4956.727379994063, 4991.4758950310425, 4981.32752078472, 4982.7010379376625, 4989.052080104769, 5000.74996250186, 5023.6985780853265, 4990.775415106329, 4999.625028122905, 4999.750012499433, 5001.124803159432, 5040.31955685985, 4190.937135942902, 4219.198352313051, 4220.198162349206, 4283.935740963888, 4354.673399495051, 3708.4808126862263, 3723.832427540767, 3744.5319335083113, 3746.4193050077283, 3765.7175711821174, 4395.692301538862, 4433.091216300651, 4434.957784920541, 4459.264221403497, 4477.0, 5119.334486516804, 5126.92309615356, 5127.2052831811125, 5129.12804823181, 5177.611679124081, 4239.915201695949, 4312.935305970413, 4431.379606855043, 4446.911061778746, 4475.48531918827, 4579.977100114545, 4601.33280674307, 4613.331066995334, 4632.444106707217, 4673.859784206479, 4753.881152971065, 4754.0, 4754.524547545287, 4757.976210118927, 4764.142454358249, 5055.16589762685, 5096.490350964874, 5114.590832733446, 5122.052420302256, 5173.198154286091, 4587.655925805511, 4620.306953956933, 4630.305454181899, 4696.084263568571, 4701.741404222664, 4992.975035124947, 5020.598352131894, 5048.495150484922, 5073.543381095719, 5136.229565565195, 5144.485551444825, 5145.305383773143, 5149.639525233198, 5153.561947234377, 5194.168932970707, 4740.407449068866, 4984.576311013604, 4985.401751789664, 4995.500449954975, 5001.42483614389, 4779.426468823764, 4870.147724148189, 4896.6572339935865, 4926.802927882988, 4996.250562415521, 4838.298446725134, 4845.030993801183, 4852.369192005158, 4862.4651288357545, 4877.95122048775, 4777.092352453023, 4978.029284289673, 4980.975095124501, 4991.775370108281, 5006.924896126634, 4651.441826980784, 4816.133096042606, 4841.903161936812, 4847.079054979543, 4909.533594308535], "value": 4636.461413602519, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [520.448324775738, 541.2557733116964, 547.4580165636019, 547.8301726464799, 552.8086972574101, 38606.0, 39934.0, 39988.000299992476, 43101.56898431015, 43629.0, 40634.18731625366, 41025.0, 41035.58964410355, 41051.0, 41073.58926410735, 7649.273319034697, 7780.533168009909, 7816.17930117338, 7835.255650713173, 8947.731568052968, 9384.483853388061, 9419.48192849393, 9468.384555003902, 9469.573869175869, 9478.28912831537, 9415.670451534197, 9462.432254064779, 9472.952635236814, 9485.620575176985, 9488.0, 9334.486603236819, 9355.111264429868, 9382.437053776795, 9410.341276110641, 9418.387804792666, 9327.20718738908, 9343.626254949793, 9418.481983490905, 9420.434773913586, 9444.48055356955, 33432.49851252233, 33560.83219583911, 33572.49641255383, 33573.83213083944, 33728.32543349144, 3359.344927739092, 3363.865445382182, 3400.880969166092, 3453.9309213815836, 3473.0796338970194, 22828.31515054551, 24770.0, 24780.25659230208, 24841.0, 24948.3762905927, 3332.2502436951736, 3536.5756109266795, 3648.945265821015, 3685.649863262986, 3700.389435743099, 234550.0, 235539.0, 235692.0, 235932.0, 236925.0, 37697.81151094227, 38979.805100974314, 45399.31901021487, 45689.31466028012, 45804.541954580694, 45491.31763023557, 45789.0, 46507.76746116315, 46621.30068048982, 46632.300515492294, 13268.601941941757, 13416.530421435204, 13423.328833558478, 13443.529476468277, 13450.529231477047, 10188.643397481053, 10199.235057370726, 10203.081722644998, 10259.487025648688, 10275.794484110276, 15606.531804045897, 15616.4534241301, 15727.68544629101, 15743.606409839846, 15989.440369587008, 6609.504287178481, 6616.536842421081, 6753.3246675332075, 6773.119494465688, 6891.448684105259, 744.3635691483703, 746.5707218349362, 759.2786852490046, 772.1930582541257, 774.1368374262656, 25123.35711321848, 26469.47061058768, 26812.0, 27139.728602714116, 27196.45607087847, 888.7333799860016, 891.1088911088912, 892.2683399612256, 895.6148855991855, 910.499225426013, 13668.453261869414, 13815.51645692416, 14096.929515352358, 14295.213763243122, 14295.57113286603, 315939.0, 317900.0, 317998.0, 318202.0, 319353.0, 47921.76039119782, 47929.0, 48406.0, 48675.026499469815, 48701.512984869696, 1055.7255113670346, 1113.6826004588654, 1168.2289688805179, 1168.9415529223504, 1170.0639488409238, 24265.729034188815, 24920.62619060752, 25948.35129121787, 26234.86882565575, 26248.212553623423, 40446.59553404546, 40624.59375406208, 40665.39001915033, 40666.79666601648, 40677.389839151845, 256917.0, 257813.0, 257869.0, 258774.0, 258852.0, 23146.76853231446, 24027.27918162458, 24037.0, 24065.759342406353, 24963.0, 35266.47100293549, 35528.289434211685, 35657.1085722859, 36004.0, 36470.0, 30266.84866575653, 30403.695963040973, 30818.691813082478, 31058.378832423678, 31754.682453175174, 29758.25604359908, 30136.698633014264, 30171.0, 30332.545011824397, 31073.68926310708, 29802.254943626584, 30235.0, 30416.239594010327, 30473.390532189675, 30481.0, 26573.202803915912, 27557.586636200875, 27640.0, 27993.0, 28439.857800710863, 21407.143714251255, 21933.451663708536, 22040.33878983633, 22068.33794986153, 22162.44593885166], "value": 16504.250767220627, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.98508059677613, 388.93971434427675, 396.7639254643488, 403.4210907347956, 420.3747596261346, 16403.589910252234, 17761.91119044406, 17877.642447151065, 17883.374081907135, 18297.35959241427, 17287.30850765968, 17380.47858564245, 17609.735853962167, 17729.202185901635, 17748.378806741766, 5184.35195600549, 5389.191621256823, 5391.946080539204, 5396.43337449568, 5404.378496472897, 5409.0, 5661.320641523023, 5687.26065611472, 5758.7408566614395, 5762.0, 5734.942650573483, 5740.942590574083, 5742.224799652056, 5744.22452968848, 5751.82744517663, 6187.102870083858, 6226.87546249077, 6235.625862448268, 6241.687915604201, 6340.904886426707, 5462.770876552783, 5598.58010649203, 5613.186088017214, 5635.6618602883955, 5644.379118296984, 21189.364319070453, 21325.36023919285, 21430.678539821914, 21488.462788430257, 21583.892080539656, 1770.9203085861038, 2351.2828587280874, 2482.3049546127113, 2510.7740303372784, 2587.1462417402213, 8827.16141966512, 9212.953935230347, 9445.811083778355, 9626.663066792698, 9847.310688251826, 1703.9744403833952, 1744.2412550540555, 1753.0708724376102, 1755.613764971704, 1767.7083281258576, 63546.0, 65124.023139653415, 74815.25184748137, 74933.25066749372, 76105.61947190229, 10697.679069627768, 10705.411202383944, 10787.78424431507, 11062.612808551663, 11168.66494005181, 15252.847471525365, 15332.923335383251, 15351.539453816404, 15390.769138462932, 15448.459303924308, 6665.766698165541, 6825.385715285648, 6833.043373927555, 7119.359257666835, 7154.284571542804, 6232.937670623326, 6281.748730050746, 6453.548251622341, 6462.353764623594, 6486.059521369375, 4600.746958917225, 4613.492515823256, 4687.10944920464, 4788.090262850117, 4806.2310030395665, 1952.6526696579315, 1971.6253911756721, 2005.4084045206698, 2018.1826360324092, 2035.450428384328, 741.7931653331403, 747.6598147842718, 750.8723517002122, 770.445279398832, 796.6614188969664, 16952.23714932831, 17213.13934303305, 17863.821361786475, 17907.820921790877, 18116.275348986157, 833.1676277590075, 878.7934835313696, 880.9867851982225, 885.3448448148324, 889.217488610032, 9601.519924003773, 9637.277204209713, 9926.404415735078, 9929.219718577197, 10053.095221430107, 456023.0, 457404.0, 479942.0, 480949.0, 483292.0, 67656.66171669209, 85232.14767852242, 89458.55270723604, 92440.30238790507, 93329.0, 973.9853902191472, 980.2011360740973, 989.7080361293436, 995.1738559742809, 996.8454889492285, 14490.85509144895, 14892.702145957237, 15534.1456219909, 15546.611334716723, 15739.606509837346, 34451.310973780885, 39562.604373957045, 40406.1918761629, 41077.589224107374, 41571.37642935419, 52349.0, 52611.47388526219, 53035.0, 53061.46938530565, 56118.43881561296, 7523.398128149625, 7585.0, 7605.88591171144, 7663.386929045551, 8252.956051534584, 10106.949465252626, 11606.941965290458, 11974.101942354184, 12432.875671243171, 12672.493100275886, 9775.0, 10038.0, 10140.340877842917, 10249.33379330371, 10894.291871028357, 10248.180145588483, 10735.302205356627, 10795.730106747395, 10836.403997780199, 10847.620333288298, 10721.678349649523, 10761.139108871115, 10854.565817367533, 10892.237543372205, 11354.772904541698, 7684.193159718149, 7930.325922296669, 7967.163447838126, 8107.918920810716, 8235.547044912588, 10279.588816447258, 10436.843447348145, 10454.581816727246, 10481.37111773296, 11088.223824332219], "value": 8582.041769083675, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1282.5767496726082, 1302.5375991523008, 1305.425612730399, 1308.110484870288, 1313.1530163044833, 7028.789136325906, 7054.329838665331, 7305.926940730591, 7479.850402991937, 7499.250074992505, 7382.0, 7486.064241969754, 7490.3633191178815, 7506.0, 7506.211847755989, 2076.6054449654557, 2092.4350425385137, 2101.8003289687454, 2111.6515774897202, 2116.947076323089, 2080.3589638996573, 2096.433962830035, 2106.8314534837255, 2113.144176608476, 2115.7778433264516, 2073.481629592602, 2077.3041031254547, 2096.077725800643, 2097.3183715292516, 2104.4633618427356, 2047.7542694876636, 2069.6585063464586, 2071.078370125295, 2072.8030837070455, 2073.067119796098, 1758.753774471575, 1801.7297405389165, 2040.0615716770303, 2056.8148866601928, 2119.597276517472, 7078.398336141434, 7295.854082918365, 7311.853762924712, 7391.22392148822, 7422.294881986203, 4453.643708503312, 4598.172328980782, 4601.3558101865765, 4604.746738929358, 4612.677112602119, 2720.1567514070503, 2840.5455127179757, 2881.7694584433193, 2903.9274018149504, 2905.3608206194604, 1042.4943902207424, 1044.185535282481, 1048.0829274384914, 1072.6031368393744, 1089.0797276301512, 9843.655472058443, 9852.852207216898, 9933.801323973552, 9999.600015999424, 10009.099181073665, 3760.52993375828, 3811.9809400952818, 3938.6652134568308, 3942.2903877301787, 4104.199681062164, 7717.536947783151, 7728.497647652885, 7749.8450030999065, 7765.107012693499, 7775.494592851446, 4494.573015563582, 4507.6168525675685, 4529.728216307032, 4541.114482675913, 4550.294704320836, 4042.535108462506, 4204.348326009474, 4255.148970205909, 4309.633681137138, 4317.84887528935, 3123.7969531980802, 3172.1435212492497, 3279.8032118072992, 3283.244853683666, 3285.7535684823733, 955.1690029674228, 958.5063692198523, 967.4147140979746, 968.3463662028103, 970.7281961050917, 731.7146312937958, 745.8023623739713, 755.5089192025227, 766.9769906902802, 781.7882282462275, 4502.549745025537, 4533.750643714561, 4617.8845528861375, 4648.907021859612, 4692.601128904013, 498.63599572312415, 849.1550906847726, 861.3539845116163, 870.3211495033884, 881.2289246908955, 2970.702929707012, 3049.9390012199633, 3068.601081859344, 3086.3210093779335, 3255.918602034968, 8288.668453261922, 8628.568571571397, 8646.308295336357, 8662.480251184948, 8863.468191908507, 8723.563821808884, 8796.692115776044, 8806.119388061143, 8810.691625793066, 8815.955920220485, 6805.421539169127, 6895.896561551581, 6896.310368963063, 6968.128983877015, 6975.232724400312, 4529.8641040768825, 4630.907381852411, 4821.710697358169, 4897.118518666674, 5041.8487445376695, 7905.130435651959, 7922.960385198037, 7997.520148791091, 8034.558099304594, 8082.393820463371, 8052.919470805217, 8069.878951815845, 8180.263776260165, 8187.71343002992, 8253.422260441952, 6310.905336420049, 6374.394432528902, 6395.360463953567, 6433.292337842927, 6448.387403196689, 6854.520183587101, 6887.586744795329, 6939.618320992192, 6952.165740111219, 6964.930350696428, 6270.341614130451, 6367.649779262185, 6378.649174295273, 6437.903431448625, 6557.901631475627, 6305.5901366411035, 6369.903329110039, 6371.267304260069, 6535.869282614226, 6536.477081833346, 5342.813001544927, 5424.83725488236, 5439.5920305978, 5445.0, 5452.400235973961, 3786.1481166737262, 3814.218085292564, 3929.115948911586, 3956.465877106669, 3996.480457540501, 3944.7041471889147, 3993.740406873544, 4071.653909417614, 4086.40747091676, 4220.810063547209], "value": 3834.848612937905, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.1161883671984, 357.4227622389841, 365.33873688623595, 365.4791921511845, 369.62298455575313, 4954.925676114856, 4981.402231732188, 4990.800367985281, 5007.49925007499, 5015.439038282814, 4962.826301079463, 4976.626752993531, 4978.103941290576, 4982.701037937717, 4989.900201995958, 3911.804409779507, 4032.9596704033024, 4062.2078694654556, 4066.79666016698, 4071.1654110907343, 4069.2675318442816, 4077.67378609712, 4080.489938757655, 4086.4483294755273, 4115.32097203961, 4079.5920407959115, 4082.4080508326274, 4095.4915638466214, 4095.5904409558952, 4096.897577560555, 3948.6446219840354, 4012.378081397388, 4037.1723796621623, 4071.857484988026, 4072.1041370898356, 3649.124210189562, 3650.8539658413633, 3655.4151335786146, 3663.175785448253, 3665.358562251622, 4827.686200396998, 4853.466118726938, 4857.781399837016, 4860.8091017700535, 4861.73260470674, 4491.865244042684, 4564.360989461478, 4570.862874113782, 4598.4711758147905, 4610.962533429985, 5222.477752224785, 5244.632875698702, 5257.5005374489365, 5258.50044245796, 5265.0, 3949.4273330366937, 3972.2254160438724, 3974.3442332015184, 3974.5230572331216, 3986.362182050858, 4881.145799485112, 5536.252605898192, 5559.332880054379, 5582.134769110754, 5590.6582420219265, 4002.219567184371, 4011.338129208706, 4023.4970628671417, 4029.6977726670616, 4035.53591337, 4106.609872062149, 4111.239420707178, 4120.711550191458, 4131.5248746393945, 4143.274926887782, 4566.452025756864, 4568.2690769477385, 4572.451305843254, 4586.495485496593, 4589.242774942164, 5075.259359945662, 5231.843044708665, 5247.248217524489, 5257.395399529027, 5263.421023687391, 1362.7206422683325, 1366.5763613279914, 1370.205280937054, 1372.6774208061097, 1373.230990645242, 932.8265142218614, 983.8868530119128, 991.2763682511807, 993.5280741647804, 996.4121168510613, 4442.489113751895, 4453.799579018887, 4467.798949047301, 4490.932636010462, 4497.775111244425, 5225.99505284463, 5233.336664334981, 5250.634834942943, 5253.763580638881, 5257.316548848626, 4329.9134017319475, 4481.619062379669, 4485.282354823213, 4490.191765482179, 4492.910141797146, 2763.5440152375036, 2773.586979327957, 2786.8852459016152, 2799.7620202283033, 2810.8594570271403, 4882.707037577757, 4899.779509922062, 4903.313536104948, 4905.0, 4910.754462276872, 5143.434222235551, 5211.941283193118, 5354.32283858071, 5369.463053694677, 5458.0721277382145, 5006.224035274538, 5010.173321401928, 5021.221710634857, 5026.5621173730715, 5046.798128074909, 5349.277847490539, 5351.839444816662, 5383.250443605922, 5389.541888939484, 5402.810901618582, 5143.4085080216255, 5205.579405576425, 5297.231901374356, 5311.920321195263, 5315.495027972336, 4855.95144048555, 4969.676970996874, 4989.650724449254, 5014.398272207359, 5025.522575355335, 3747.9625203747614, 3809.1239015026144, 3810.7713537187856, 3822.0062783677, 3827.3302172120325, 5394.190871369169, 5404.621676482766, 5407.783688652567, 5421.756020978986, 5423.29497165366, 4932.090066860569, 5184.0, 5236.816711415082, 5239.842804715865, 5272.947270527399, 5179.896402071862, 5185.896282074413, 5187.792488300426, 5188.273641690242, 5190.870228244324, 5124.923126153185, 5125.769340379767, 5150.94405646849, 5156.1750119980625, 5172.7413629319135, 4986.8753281168265, 5216.947830521646, 5222.817201397933, 5271.499207575274, 5282.418933917189, 5098.770555324945, 5113.948860511347, 5115.1559992600805, 5141.845744627667, 5143.61422893277], "value": 4001.9738173846536, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.7157957867905, 670.7283550162186, 724.9383802376798, 728.1662496441571, 762.157815613747, 12383.504659813609, 13297.800532992012, 13304.800427993589, 13304.933475332635, 13339.466421343159, 12456.19034762742, 12506.0, 13171.73656526869, 13647.795283070762, 13721.3825377858, 753.3333000294724, 800.9038915330154, 818.0878320672438, 818.6029775558849, 819.4468733604793, 796.1839114907209, 812.4556547113418, 812.8496228197773, 813.7558732380292, 815.6615004773012, 844.4046946902449, 848.1687945813106, 854.5897968974896, 855.5807654249404, 856.092541905579, 843.2158092973532, 846.318713435684, 846.7459762071385, 847.639753104928, 848.2111636178352, 615.59986009094, 623.5697368815503, 623.6819222196713, 624.3912185618985, 624.5846512069469, 13810.861891381159, 13844.10013349129, 14017.299135043208, 14072.22602756848, 14265.857341426557, 12679.0, 12975.805362919564, 13000.674983125498, 13195.142315749446, 13425.530106446326, 10475.0, 10622.78754424915, 10722.78554428907, 10807.189460790472, 10847.349159050482, 3023.8639261233297, 3041.893533726331, 3085.3674996625637, 3091.2426455518544, 3105.4410206162884, 35727.10682232939, 44352.778236108934, 57122.0, 62520.37479625236, 66515.3348466514, 10391.168706503462, 10451.216158788122, 10490.31812932157, 10632.521536530874, 10751.78496430067, 18420.35528756487, 18594.81405185958, 18597.34909278196, 18801.435956921316, 18819.90590047041, 6529.706163222666, 6560.540762146604, 6594.043863639841, 6691.59850408977, 6833.111695479655, 3602.189507360823, 3602.531670882821, 3635.8909232723063, 3655.9634403656155, 3671.7980511072146, 2896.8846993907496, 2993.9850300748794, 3096.7987080839675, 3258.885938992124, 3262.9021129366156, 584.6404461256296, 586.4956137721556, 594.174098003775, 604.2235727090667, 615.1418770814778, 601.1493736362997, 627.1502114634695, 630.4105661206786, 638.7975575387543, 648.4974145037539, 7814.57019863913, 8769.298456123495, 8809.647614095493, 8831.823363532694, 9014.86477702835, 632.3441545508881, 649.7108786589922, 659.831742905556, 662.718344703499, 666.3602941176525, 3970.6029397060643, 4127.566605506378, 4161.417401563784, 4256.446661933991, 4264.893377665583, 49753.50246497561, 51336.0, 56168.0, 62384.68807655932, 67018.66490667516, 56149.15776263359, 57229.42770572324, 57647.711761441766, 58738.41261587415, 58876.70561647164, 51756.223656644426, 52001.739991301314, 52659.736701317015, 52795.20807187895, 53144.46855531395, 8020.558869262013, 8065.717699880476, 8153.306968907709, 8218.753437396888, 8378.706745263886, 34525.0, 35688.0, 36291.27417451689, 36330.091747706516, 36741.44887826738, 42053.36919946264, 45920.54079459162, 46218.53781462142, 46628.53371466242, 46725.53274467212, 10479.895201047892, 10480.63317783874, 10502.52738626779, 10580.735481613021, 10741.462926853783, 11344.979689485685, 11351.432428378714, 11453.19827612059, 11575.305481671126, 12020.158588898694, 10399.688009359732, 10744.194185435967, 10759.623413180501, 10892.836607451052, 11263.098952083978, 9948.253880959102, 10158.695239142837, 10246.487675616338, 10327.277090603886, 10634.0, 9742.805143897223, 9830.75423114428, 9909.355891867006, 9928.305018648625, 10028.29801913859, 7852.0, 7999.320057795153, 8207.95896020516, 8302.377321700777, 8384.455010424303, 7547.792353223677, 7874.56689882062, 7920.0, 7965.203479651988, 8102.432829701865], "value": 6179.353798811024, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1596.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 117.0, 117.0, 115.0, 116.0, 117.0, 116.0, 115.0, 115.0, 114.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 128.0, 130.0, 177.0, 132.0, 134.0, 139.0, 135.0, 160.0, 131.0], "value": 142.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 168.0, 170.0, 173.0, 165.0, 167.0, 167.0, 164.0, 165.0], "value": 166.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 77.0, 121.0, 78.0, 77.0, 77.0, 77.0, 111.0, 77.0], "value": 84.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 113.0, 84.0, 85.0, 84.0, 97.0, 84.0, 84.0, 112.0], "value": 91.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 115.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 152.0, 151.0, 150.0, 151.0, 152.0, 150.0, 151.0, 150.0, 151.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 97.0, 96.0, 96.0, 97.0, 97.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 69.0, 70.0, 72.0, 70.0, 70.0, 69.0, 71.0], "value": 69.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 64.0, 65.0, 66.0, 65.0, 66.0, 66.0, 65.0, 66.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 97.0, 96.0, 92.0, 87.0, 98.0, 87.0, 88.0, 87.0, 91.0], "value": 91.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 138.0, 144.0, 136.0, 138.0, 149.0, 144.0, 142.0, 182.0, 158.0], "value": 148.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 194.0, 187.0, 185.0, 188.0, 188.0, 192.0, 187.0, 186.0, 185.0], "value": 187.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 72.0, 72.0, 70.0, 76.0, 71.0, 70.0, 76.0, 70.0], "value": 72.7, "unit": "score"}]}]} 06:15:27 INFO - Return code: 0 06:15:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:15:27 ERROR - # TBPL SUCCESS # 06:15:27 INFO - Running post-action listener: _resource_record_post_action 06:15:27 INFO - [mozharness: 2017-02-21 14:15:27.318173Z] Finished run-tests step (success) 06:15:27 INFO - Running post-run listener: _resource_record_post_run 06:15:27 INFO - Total resource usage - Wall time: 1204s; CPU: 14.0%; Read bytes: 4096; Write bytes: 358625280; Read time: 8; Write time: 507712 06:15:27 INFO - TinderboxPrint: CPU usage
13.5% 06:15:27 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 06:15:27 INFO - TinderboxPrint: I/O write bytes / time
358,625,280 / 507,712 06:15:27 INFO - TinderboxPrint: CPU idle
8,310.1 (86.5%) 06:15:27 INFO - TinderboxPrint: CPU user
1,235.3 (12.9%) 06:15:27 INFO - TinderboxPrint: Swap in / out
0 / 0 06:15:27 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 105615360; Read time: 0; Write time: 210568 06:15:27 INFO - run-tests - Wall time: 1189s; CPU: 14.0%; Read bytes: 4096; Write bytes: 253009920; Read time: 8; Write time: 297144 06:15:27 INFO - Running post-run listener: _upload_blobber_files 06:15:27 INFO - Blob upload gear active. 06:15:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:15:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:15:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:15:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:15:28 INFO - (blobuploader) - INFO - Open directory for files ... 06:15:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:15:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:15:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:29 INFO - (blobuploader) - INFO - Done attempting. 06:15:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:15:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:15:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:34 INFO - (blobuploader) - INFO - Done attempting. 06:15:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:15:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:15:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:35 INFO - (blobuploader) - INFO - Done attempting. 06:15:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:15:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:15:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:15:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:15:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:15:36 INFO - (blobuploader) - INFO - Done attempting. 06:15:36 INFO - (blobuploader) - INFO - Iteration through files over. 06:15:36 INFO - Return code: 0 06:15:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:15:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:15:36 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3629d293f00315e4eacf4c4731552b226bd6cd6aac2fe208b0d9cbfe47ea3f71b959a8045f3b2f8acd636cb87cd833487fc432362bb96cc4c052321cd65ed258", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3592755d24aee050f7d1a52e032a07b69b92b2399402ec2a71fa8c52a44fc18d956c3a21221c85f1dfafa47c697b70b388ab170afbdfa735d0d9e291277f37eb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/493bf79227c3bdf361f4be6b657298e0e66cbc55f2e0f8d266bfcf8052c1a9e1268360c39374ab2dcff825e30194a509159ecb0814c3686130d409cac2a127a6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/68423a8edd3992d00564e906caed15ce617f2e5e7fc26493685e0351f6bda93f3014d62d856dc985a6d3bb757cdf35d548ef642b855a440c2efde7525cd57865"} 06:15:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:15:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:15:36 INFO - Contents: 06:15:36 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3629d293f00315e4eacf4c4731552b226bd6cd6aac2fe208b0d9cbfe47ea3f71b959a8045f3b2f8acd636cb87cd833487fc432362bb96cc4c052321cd65ed258", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3592755d24aee050f7d1a52e032a07b69b92b2399402ec2a71fa8c52a44fc18d956c3a21221c85f1dfafa47c697b70b388ab170afbdfa735d0d9e291277f37eb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/493bf79227c3bdf361f4be6b657298e0e66cbc55f2e0f8d266bfcf8052c1a9e1268360c39374ab2dcff825e30194a509159ecb0814c3686130d409cac2a127a6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/68423a8edd3992d00564e906caed15ce617f2e5e7fc26493685e0351f6bda93f3014d62d856dc985a6d3bb757cdf35d548ef642b855a440c2efde7525cd57865"} 06:15:36 INFO - Running post-run listener: copy_logs_to_upload_dir 06:15:36 INFO - Copying logs to upload dir... 06:15:36 INFO - mkdir: /builds/slave/test/build/upload/logs 06:15:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.988622 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-02-21 06:15:36.103554) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-21 06:15:36.106663) ========= 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=745a4373a2a16df560f216e000000264-1487685267.749100-1152801385 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/3629d293f00315e4eacf4c4731552b226bd6cd6aac2fe208b0d9cbfe47ea3f71b959a8045f3b2f8acd636cb87cd833487fc432362bb96cc4c052321cd65ed258", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3592755d24aee050f7d1a52e032a07b69b92b2399402ec2a71fa8c52a44fc18d956c3a21221c85f1dfafa47c697b70b388ab170afbdfa735d0d9e291277f37eb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/493bf79227c3bdf361f4be6b657298e0e66cbc55f2e0f8d266bfcf8052c1a9e1268360c39374ab2dcff825e30194a509159ecb0814c3686130d409cac2a127a6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/68423a8edd3992d00564e906caed15ce617f2e5e7fc26493685e0351f6bda93f3014d62d856dc985a6d3bb757cdf35d548ef642b855a440c2efde7525cd57865"} build_url:https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011372 build_url: 'https://queue.taskcluster.net/v1/task/dA2cOnyfR8CbckA5mVdflg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3629d293f00315e4eacf4c4731552b226bd6cd6aac2fe208b0d9cbfe47ea3f71b959a8045f3b2f8acd636cb87cd833487fc432362bb96cc4c052321cd65ed258", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3592755d24aee050f7d1a52e032a07b69b92b2399402ec2a71fa8c52a44fc18d956c3a21221c85f1dfafa47c697b70b388ab170afbdfa735d0d9e291277f37eb", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/493bf79227c3bdf361f4be6b657298e0e66cbc55f2e0f8d266bfcf8052c1a9e1268360c39374ab2dcff825e30194a509159ecb0814c3686130d409cac2a127a6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/68423a8edd3992d00564e906caed15ce617f2e5e7fc26493685e0351f6bda93f3014d62d856dc985a6d3bb757cdf35d548ef642b855a440c2efde7525cd57865"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-21 06:15:36.131205) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 06:15:36.131523) ========= 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=745a4373a2a16df560f216e000000264-1487685267.749100-1152801385 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004477 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-21 06:15:36.219209) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-21 06:15:36.219523) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-21 06:15:36.219798) ========= ========= Total master_lag: 0.17 =========