builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-024 starttime: 1488561057.57 results: retry (5) revision: 86e1748ab478e23f7a73e2c4af11a310d26fbda9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-03 09:10:57.570427) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-03 09:10:57.570875) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-03 09:10:57.571145) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-03 09:10:57.586269) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 09:10:57.586685) ========= 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=41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-03 09:10:57-- 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% 216M=0s 2017-03-03 09:10:58 (216 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.391029 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 09:10:57.992932) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 09:10:57.993409) ========= 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=41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019160 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 09:10:58.043862) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 09:10:58.044205) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 86e1748ab478e23f7a73e2c4af11a310d26fbda9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 86e1748ab478e23f7a73e2c4af11a310d26fbda9 --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=41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-03 09:10:58,169 truncating revision to first 12 chars 2017-03-03 09:10:58,169 Setting DEBUG logging. 2017-03-03 09:10:58,169 attempt 1/10 2017-03-03 09:10:58,169 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/86e1748ab478?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-03 09:10:58,744 unpacking tar archive at: mozilla-inbound-86e1748ab478/testing/mozharness/ program finished with exit code 0 elapsedTime=0.881169 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 09:10:58.940960) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-03 09:10:58.941511) ========= script_repo_revision: 86e1748ab478e23f7a73e2c4af11a310d26fbda9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-03 09:10:58.942174) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-03 09:10:58.942627) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-03 09:10:58.953823) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 20 mins, 0 secs) (at 2017-03-03 09:10:58.954362) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:10:59 INFO - MultiFileLogger online at 20170303 09:10:59 in /builds/slave/test 09:10:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 09:10:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:10:59 INFO - {'append_to_log': False, 09:10:59 INFO - 'base_work_dir': '/builds/slave/test', 09:10:59 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 09:10:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:10:59 INFO - 'branch': 'Mozilla-Inbound', 09:10:59 INFO - 'buildbot_json_path': 'buildprops.json', 09:10:59 INFO - 'config_files': ('talos/linux_config.py',), 09:10:59 INFO - 'default_actions': ('clobber', 09:10:59 INFO - 'read-buildbot-config', 09:10:59 INFO - 'download-and-extract', 09:10:59 INFO - 'populate-webroot', 09:10:59 INFO - 'create-virtualenv', 09:10:59 INFO - 'install', 09:10:59 INFO - 'run-tests'), 09:10:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:10:59 INFO - 'download_minidump_stackwalk': True, 09:10:59 INFO - 'download_symbols': 'ondemand', 09:10:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:10:59 INFO - 'tooltool.py': '/tools/tooltool.py', 09:10:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:10:59 INFO - '/tools/misc-python/virtualenv.py')}, 09:10:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:10:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:10:59 INFO - 'gecko_profile': False, 09:10:59 INFO - 'gecko_profile_interval': 0, 09:10:59 INFO - 'installer_path': 'installer.exe', 09:10:59 INFO - 'log_level': 'info', 09:10:59 INFO - 'log_name': 'talos', 09:10:59 INFO - 'log_to_console': True, 09:10:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:10:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:10:59 INFO - 'opt_config_files': (), 09:10:59 INFO - 'pip_index': False, 09:10:59 INFO - 'suite': 'dromaeojs', 09:10:59 INFO - 'system_bits': '32', 09:10:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:10:59 INFO - 'title': 'talos-linux64-ix-024', 09:10:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:10:59 INFO - 'use_talos_json': True, 09:10:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:10:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:10:59 INFO - 'work_dir': 'build'} 09:10:59 INFO - [mozharness: 2017-03-03 17:10:59.183481Z] Running clobber step. 09:10:59 INFO - Running pre-action listener: _resource_record_pre_action 09:10:59 INFO - Running main action method: clobber 09:10:59 INFO - rmtree: /builds/slave/test/build 09:10:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:11:00 INFO - Running post-action listener: _resource_record_post_action 09:11:00 INFO - [mozharness: 2017-03-03 17:11:00.101017Z] Finished clobber step (success) 09:11:00 INFO - [mozharness: 2017-03-03 17:11:00.101117Z] Running read-buildbot-config step. 09:11:00 INFO - Running pre-action listener: _resource_record_pre_action 09:11:00 INFO - Running main action method: read_buildbot_config 09:11:00 INFO - Using buildbot properties: 09:11:00 INFO - { 09:11:00 INFO - "project": "", 09:11:00 INFO - "product": "firefox", 09:11:00 INFO - "who": "gbrown@mozilla.com", 09:11:00 INFO - "installer_path": "public/build/target.tar.bz2", 09:11:00 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 09:11:00 INFO - "repository": "https://hg.mozilla.org/integration/mozilla-inbound/", 09:11:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 09:11:00 INFO - "stage_platform": "linux64-pgo", 09:11:00 INFO - "basedir": "/builds/slave/test", 09:11:00 INFO - "buildnumber": 2935, 09:11:00 INFO - "platform": "ubuntu64_hw", 09:11:00 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:11:00 INFO - "slavebuilddir": "test", 09:11:00 INFO - "taskId": "FS_5H_-rSdOsS5ESEt_P9Q", 09:11:00 INFO - "branch": "mozilla-inbound", 09:11:00 INFO - "script_repo_revision": "production", 09:11:00 INFO - "revision": "86e1748ab478e23f7a73e2c4af11a310d26fbda9", 09:11:00 INFO - "slavename": "talos-linux64-ix-024", 09:11:00 INFO - "repo_path": "integration/mozilla-inbound" 09:11:00 INFO - } 09:11:00 INFO - Finding installer, test and symbols from parent task. 09:11:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FS_5H_-rSdOsS5ESEt_P9Q'}, attempt #1 09:11:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FS_5H_-rSdOsS5ESEt_P9Q'}, attempt #1 09:11:01 INFO - Task dependencies: WV0FVq3eQ4-1zlE05Tko5Q 09:11:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q'}, attempt #1 09:11:01 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.tar.bz2 09:11:01 INFO - Set test_url: https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/test_packages.json 09:11:01 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.crashreporter-symbols.zip 09:11:01 INFO - Running post-action listener: _resource_record_post_action 09:11:01 INFO - [mozharness: 2017-03-03 17:11:01.600832Z] Finished read-buildbot-config step (success) 09:11:01 INFO - [mozharness: 2017-03-03 17:11:01.601047Z] Running download-and-extract step. 09:11:01 INFO - Running pre-action listener: _resource_record_pre_action 09:11:01 INFO - Running main action method: download_and_extract 09:11:01 INFO - mkdir: /builds/slave/test/build/tests 09:11:01 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')]} 09:11:01 INFO - trying https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.test_packages.json 09:11:01 INFO - Downloading https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:11:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:11:02 INFO - Downloaded 896 bytes. 09:11:02 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:11:02 INFO - Using the following test package requirements: 09:11:02 INFO - {u'common': [u'target.common.tests.zip'], 09:11:02 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:11:02 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:11:02 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:11:02 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:11:02 INFO - u'mozbase': [u'target.common.tests.zip'], 09:11:02 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:11:02 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:11:02 INFO - u'web-platform': [u'target.common.tests.zip', 09:11:02 INFO - u'target.web-platform.tests.zip'], 09:11:02 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:11:02 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:11:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.common.tests.zip 09:11:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:11:02 INFO - Fetch https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.common.tests.zip into memory 09:11:03 INFO - Expected file size: 59620381 09:11:03 INFO - Obtained file size: 59620381 09:11:12 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:11:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.common.tests.zip 09:11:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:11:12 INFO - Fetch https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.common.tests.zip into memory 09:11:14 INFO - Expected file size: 59620381 09:11:14 INFO - Obtained file size: 59620381 09:11:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.talos.tests.zip 09:11:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:11:24 INFO - Fetch https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.talos.tests.zip into memory 09:11:25 INFO - Expected file size: 14404424 09:11:25 INFO - Obtained file size: 14404424 09:11:25 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')]} 09:11:25 INFO - trying https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.tar.bz2 09:11:25 INFO - Downloading https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:11:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:11:26 INFO - Downloaded 67213324 bytes. 09:11:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.tar.bz2 09:11:26 INFO - mkdir: /builds/slave/test/properties 09:11:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:11:26 INFO - Writing to file /builds/slave/test/properties/build_url 09:11:26 INFO - Contents: 09:11:26 INFO - build_url:https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.tar.bz2 09:11:26 INFO - Running post-action listener: _resource_record_post_action 09:11:26 INFO - Running post-action listener: set_extra_try_arguments 09:11:26 INFO - [mozharness: 2017-03-03 17:11:26.897260Z] Finished download-and-extract step (success) 09:11:26 INFO - [mozharness: 2017-03-03 17:11:26.897355Z] Running populate-webroot step. 09:11:26 INFO - Running pre-action listener: _resource_record_pre_action 09:11:26 INFO - Running main action method: populate_webroot 09:11:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:11:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:11:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:11:26 INFO - '--tppagecycles', 09:11:26 INFO - '7'], 09:11:26 INFO - 'tests': ['tsvgm']}}, 09:11:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:11:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:11:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:11:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:11:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:11:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:11:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:11:26 INFO - 'talos_options': ['--disable-e10s'], 09:11:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:11:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:11:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:11:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:11:26 INFO - 'talos_options': ['--disable-e10s'], 09:11:26 INFO - 'tests': ['damp', 'tps']}, 09:11:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:11:26 INFO - 'tests': ['damp', 'tps']}, 09:11:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:11:26 INFO - 'tests': ['dromaeo_dom']}, 09:11:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:11:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:11:26 INFO - 'tests': ['basic_compositor_video']}, 09:11:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:11:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:11:26 INFO - 'tests': ['a11yr', 09:11:26 INFO - 'ts_paint', 09:11:26 INFO - 'tpaint', 09:11:26 INFO - 'sessionrestore', 09:11:26 INFO - 'sessionrestore_no_auto_restore', 09:11:26 INFO - 'tabpaint']}, 09:11:26 INFO - 'other-e10s': {'tests': ['a11yr', 09:11:26 INFO - 'ts_paint', 09:11:26 INFO - 'tpaint', 09:11:26 INFO - 'sessionrestore', 09:11:26 INFO - 'sessionrestore_no_auto_restore', 09:11:26 INFO - 'tabpaint']}, 09:11:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:11:26 INFO - 'ts_paint', 09:11:26 INFO - 'tpaint', 09:11:26 INFO - 'sessionrestore', 09:11:26 INFO - 'sessionrestore_no_auto_restore', 09:11:26 INFO - 'tabpaint']}, 09:11:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:11:26 INFO - 'ts_paint', 09:11:26 INFO - 'tpaint', 09:11:26 INFO - 'sessionrestore', 09:11:26 INFO - 'sessionrestore_no_auto_restore', 09:11:26 INFO - 'tabpaint']}, 09:11:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:11:26 INFO - 'tests': ['a11yr', 09:11:26 INFO - 'ts_paint', 09:11:26 INFO - 'tpaint', 09:11:26 INFO - 'sessionrestore', 09:11:26 INFO - 'sessionrestore_no_auto_restore', 09:11:26 INFO - 'tabpaint']}, 09:11:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:11:26 INFO - 'tests': ['a11yr', 09:11:26 INFO - 'ts_paint', 09:11:26 INFO - 'tpaint', 09:11:26 INFO - 'sessionrestore', 09:11:26 INFO - 'sessionrestore_no_auto_restore', 09:11:26 INFO - 'tabpaint']}, 09:11:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:11:26 INFO - 'tests': ['tsvgx', 09:11:26 INFO - 'tsvgr_opacity', 09:11:26 INFO - 'tart', 09:11:26 INFO - 'tscrollx', 09:11:26 INFO - 'cart', 09:11:26 INFO - 'tsvg_static']}, 09:11:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:11:26 INFO - 'tsvgr_opacity', 09:11:26 INFO - 'tart', 09:11:26 INFO - 'tscrollx', 09:11:26 INFO - 'cart', 09:11:26 INFO - 'tsvg_static']}, 09:11:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:11:26 INFO - 'talos_options': ['--disable-e10s'], 09:11:26 INFO - 'tests': ['tp5o']}, 09:11:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:11:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:11:26 INFO - 'talos_options': ['--disable-e10s', 09:11:26 INFO - '--xperf_path', 09:11:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:11:26 INFO - 'tests': ['tp5n']}, 09:11:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:11:26 INFO - 'talos_options': ['--xperf_path', 09:11:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:11:26 INFO - 'tests': ['tp5n']}}, 09:11:26 INFO - 'talos.zip': {'path': '', 09:11:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:11:26 INFO - Running post-action listener: _resource_record_post_action 09:11:26 INFO - [mozharness: 2017-03-03 17:11:26.910387Z] Finished populate-webroot step (success) 09:11:26 INFO - [mozharness: 2017-03-03 17:11:26.910476Z] Running create-virtualenv step. 09:11:26 INFO - Running pre-action listener: _resource_record_pre_action 09:11:26 INFO - Running main action method: create_virtualenv 09:11:26 INFO - Creating virtualenv /builds/slave/test/build/venv 09:11:26 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 09:11:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:11:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:11:27 INFO - Using real prefix '/usr' 09:11:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:11:27 INFO - Installing distribute.............................................................................................................................................................................................done. 09:11:29 INFO - Installing pip.................done. 09:11:29 INFO - Return code: 0 09:11:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:11:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:11:29 INFO - Reading from file tmpfile_stdout 09:11:29 INFO - Output received: 09:11:29 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) 09:11:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:29 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 0xe871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1124b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1122290>, '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 0x1122b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1123040>, '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': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', '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 09:11:29 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 09:11:29 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 09:11:29 INFO - Using env: {'DISPLAY': ':0', 09:11:29 INFO - 'HOME': '/home/cltbld', 09:11:29 INFO - 'LANG': 'en_US.UTF-8', 09:11:29 INFO - 'LANGUAGE': 'en_US:en', 09:11:29 INFO - 'LOGNAME': 'cltbld', 09:11:29 INFO - 'MAIL': '/var/mail/cltbld', 09:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:29 INFO - 'MOZ_NO_REMOTE': '1', 09:11:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:29 INFO - 'NO_EM_RESTART': '1', 09:11:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:29 INFO - 'PWD': '/builds/slave/test', 09:11:29 INFO - 'SHELL': '/bin/bash', 09:11:29 INFO - 'SHLVL': '1', 09:11:29 INFO - 'TERM': 'linux', 09:11:29 INFO - 'TMOUT': '86400', 09:11:29 INFO - 'USER': 'cltbld', 09:11:29 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', 09:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:29 INFO - '_': '/tools/buildbot/bin/python'} 09:11:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:29 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 09:11:29 INFO - Cleaning up... 09:11:29 INFO - Return code: 0 09:11:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:29 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 0xe871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1124b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1122290>, '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 0x1122b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1123040>, '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': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', '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 09:11:29 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 09:11:29 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 09:11:29 INFO - Using env: {'DISPLAY': ':0', 09:11:29 INFO - 'HOME': '/home/cltbld', 09:11:29 INFO - 'LANG': 'en_US.UTF-8', 09:11:29 INFO - 'LANGUAGE': 'en_US:en', 09:11:29 INFO - 'LOGNAME': 'cltbld', 09:11:29 INFO - 'MAIL': '/var/mail/cltbld', 09:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:29 INFO - 'MOZ_NO_REMOTE': '1', 09:11:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:29 INFO - 'NO_EM_RESTART': '1', 09:11:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:29 INFO - 'PWD': '/builds/slave/test', 09:11:29 INFO - 'SHELL': '/bin/bash', 09:11:29 INFO - 'SHLVL': '1', 09:11:29 INFO - 'TERM': 'linux', 09:11:29 INFO - 'TMOUT': '86400', 09:11:29 INFO - 'USER': 'cltbld', 09:11:29 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', 09:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:29 INFO - '_': '/tools/buildbot/bin/python'} 09:11:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:30 INFO - Downloading/unpacking psutil>=3.1.1 09:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:30 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 09:11:30 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 09:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:11:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:11:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:11:32 INFO - Installing collected packages: psutil 09:11:32 INFO - Running setup.py install for psutil 09:11:32 INFO - building 'psutil._psutil_linux' extension 09:11:32 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 09:11:32 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 09:11:32 INFO - building 'psutil._psutil_posix' extension 09:11:32 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 09:11:32 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 09:11:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:11:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:11:32 INFO - Successfully installed psutil 09:11:32 INFO - Cleaning up... 09:11:32 INFO - Return code: 0 09:11:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:11:32 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')]} 09:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:32 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')]} 09:11:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:32 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 0xe871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1124b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1122290>, '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 0x1122b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1123040>, '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': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', '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 09:11:32 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 09:11:32 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 09:11:32 INFO - Using env: {'DISPLAY': ':0', 09:11:32 INFO - 'HOME': '/home/cltbld', 09:11:32 INFO - 'LANG': 'en_US.UTF-8', 09:11:32 INFO - 'LANGUAGE': 'en_US:en', 09:11:32 INFO - 'LOGNAME': 'cltbld', 09:11:32 INFO - 'MAIL': '/var/mail/cltbld', 09:11:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:32 INFO - 'MOZ_NO_REMOTE': '1', 09:11:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:32 INFO - 'NO_EM_RESTART': '1', 09:11:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:32 INFO - 'PWD': '/builds/slave/test', 09:11:32 INFO - 'SHELL': '/bin/bash', 09:11:32 INFO - 'SHLVL': '1', 09:11:32 INFO - 'TERM': 'linux', 09:11:32 INFO - 'TMOUT': '86400', 09:11:32 INFO - 'USER': 'cltbld', 09:11:32 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', 09:11:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:32 INFO - '_': '/tools/buildbot/bin/python'} 09:11:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:11:33 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 09:11:33 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 09:11:33 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 09:11:33 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 09:11:33 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 09:11:33 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 09:11:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:11:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:11:35 INFO - Installing collected packages: mozsystemmonitor 09:11:35 INFO - Running setup.py install for mozsystemmonitor 09:11:35 INFO - Successfully installed mozsystemmonitor 09:11:35 INFO - Cleaning up... 09:11:35 INFO - Return code: 0 09:11:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:11:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xe871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1124b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1122290>, '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 0x1122b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1123040>, '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': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', '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 09:11:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:11:35 INFO - Using env: {'DISPLAY': ':0', 09:11:35 INFO - 'HOME': '/home/cltbld', 09:11:35 INFO - 'LANG': 'en_US.UTF-8', 09:11:35 INFO - 'LANGUAGE': 'en_US:en', 09:11:35 INFO - 'LOGNAME': 'cltbld', 09:11:35 INFO - 'MAIL': '/var/mail/cltbld', 09:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:35 INFO - 'MOZ_NO_REMOTE': '1', 09:11:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:35 INFO - 'NO_EM_RESTART': '1', 09:11:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:35 INFO - 'PWD': '/builds/slave/test', 09:11:35 INFO - 'SHELL': '/bin/bash', 09:11:35 INFO - 'SHLVL': '1', 09:11:35 INFO - 'TERM': 'linux', 09:11:35 INFO - 'TMOUT': '86400', 09:11:35 INFO - 'USER': 'cltbld', 09:11:35 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', 09:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:35 INFO - '_': '/tools/buildbot/bin/python'} 09:11:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:35 INFO - Downloading/unpacking jsonschema==2.5.1 09:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:11:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:11:37 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 09:11:37 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 09:11:37 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 09:11:37 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 09:11:37 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 09:11:37 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 09:11:37 INFO - Downloading functools32-3.2.3-2.tar.gz 09:11:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:11:37 INFO - warning: no files found matching '*.txt' 09:11:37 INFO - no previously-included directories found matching 'build' 09:11:37 INFO - no previously-included directories found matching 'dist' 09:11:37 INFO - no previously-included directories found matching '.git*' 09:11:37 INFO - Installing collected packages: jsonschema, functools32 09:11:37 INFO - Running setup.py install for functools32 09:11:37 INFO - warning: no files found matching '*.txt' 09:11:37 INFO - no previously-included directories found matching 'build' 09:11:37 INFO - no previously-included directories found matching 'dist' 09:11:37 INFO - no previously-included directories found matching '.git*' 09:11:37 INFO - Successfully installed jsonschema functools32 09:11:38 INFO - Cleaning up... 09:11:38 INFO - Return code: 0 09:11:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:11: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')]} 09:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11: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')]} 09:11:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xe871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1124b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1122290>, '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 0x1122b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1123040>, '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': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', '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 09:11:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:11:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:11:38 INFO - Using env: {'DISPLAY': ':0', 09:11:38 INFO - 'HOME': '/home/cltbld', 09:11:38 INFO - 'LANG': 'en_US.UTF-8', 09:11:38 INFO - 'LANGUAGE': 'en_US:en', 09:11:38 INFO - 'LOGNAME': 'cltbld', 09:11:38 INFO - 'MAIL': '/var/mail/cltbld', 09:11:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:38 INFO - 'MOZ_NO_REMOTE': '1', 09:11:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:38 INFO - 'NO_EM_RESTART': '1', 09:11:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:38 INFO - 'PWD': '/builds/slave/test', 09:11:38 INFO - 'SHELL': '/bin/bash', 09:11:38 INFO - 'SHLVL': '1', 09:11:38 INFO - 'TERM': 'linux', 09:11:38 INFO - 'TMOUT': '86400', 09:11:38 INFO - 'USER': 'cltbld', 09:11:38 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', 09:11:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:38 INFO - '_': '/tools/buildbot/bin/python'} 09:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:11:38 INFO - Cleaning up... 09:11:38 INFO - Return code: 0 09:11:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:11: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')]} 09:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11: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')]} 09:11:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xe871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1124b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1122290>, '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 0x1122b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1123040>, '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': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', '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 09:11:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:11:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:11:38 INFO - Using env: {'DISPLAY': ':0', 09:11:38 INFO - 'HOME': '/home/cltbld', 09:11:38 INFO - 'LANG': 'en_US.UTF-8', 09:11:38 INFO - 'LANGUAGE': 'en_US:en', 09:11:38 INFO - 'LOGNAME': 'cltbld', 09:11:38 INFO - 'MAIL': '/var/mail/cltbld', 09:11:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:38 INFO - 'MOZ_NO_REMOTE': '1', 09:11:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:38 INFO - 'NO_EM_RESTART': '1', 09:11:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:38 INFO - 'PWD': '/builds/slave/test', 09:11:38 INFO - 'SHELL': '/bin/bash', 09:11:38 INFO - 'SHLVL': '1', 09:11:38 INFO - 'TERM': 'linux', 09:11:38 INFO - 'TMOUT': '86400', 09:11:38 INFO - 'USER': 'cltbld', 09:11:38 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', 09:11:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:38 INFO - '_': '/tools/buildbot/bin/python'} 09:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:38 INFO - Downloading/unpacking blobuploader==1.2.4 09:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:40 INFO - Downloading blobuploader-1.2.4.tar.gz 09:11:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:11:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:11:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:11:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:11:41 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 09:11:41 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 09:11:41 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 09:11:41 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 09:11:41 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 09:11:41 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 09:11:41 INFO - Downloading docopt-0.6.1.tar.gz 09:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:11:41 INFO - Installing collected packages: blobuploader, requests, docopt 09:11:41 INFO - Running setup.py install for blobuploader 09:11:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:11:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:11:41 INFO - Running setup.py install for requests 09:11:41 INFO - Running setup.py install for docopt 09:11:41 INFO - Successfully installed blobuploader requests docopt 09:11:41 INFO - Cleaning up... 09:11:42 INFO - Return code: 0 09:11:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:42 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')]} 09:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:42 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')]} 09:11:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:42 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 0xe871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1124b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1122290>, '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 0x1122b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1123040>, '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': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', '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 09:11:42 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 09:11:42 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 09:11:42 INFO - Using env: {'DISPLAY': ':0', 09:11:42 INFO - 'HOME': '/home/cltbld', 09:11:42 INFO - 'LANG': 'en_US.UTF-8', 09:11:42 INFO - 'LANGUAGE': 'en_US:en', 09:11:42 INFO - 'LOGNAME': 'cltbld', 09:11:42 INFO - 'MAIL': '/var/mail/cltbld', 09:11:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:42 INFO - 'MOZ_NO_REMOTE': '1', 09:11:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:42 INFO - 'NO_EM_RESTART': '1', 09:11:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:42 INFO - 'PWD': '/builds/slave/test', 09:11:42 INFO - 'SHELL': '/bin/bash', 09:11:42 INFO - 'SHLVL': '1', 09:11:42 INFO - 'TERM': 'linux', 09:11:42 INFO - 'TMOUT': '86400', 09:11:42 INFO - 'USER': 'cltbld', 09:11:42 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', 09:11:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:42 INFO - '_': '/tools/buildbot/bin/python'} 09:11:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:11:42 INFO - Running setup.py (path:/tmp/pip-T3EqfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:11:42 INFO - Running setup.py (path:/tmp/pip-fCNJ9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:11:42 INFO - Running setup.py (path:/tmp/pip-OZdDWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:11:42 INFO - Running setup.py (path:/tmp/pip-WmAZIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:11:42 INFO - Running setup.py (path:/tmp/pip-bMdhNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:11:42 INFO - Running setup.py (path:/tmp/pip-tfyXx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:11:42 INFO - Running setup.py (path:/tmp/pip-x7yjw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:11:42 INFO - Running setup.py (path:/tmp/pip-5D0iPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:11:42 INFO - Running setup.py (path:/tmp/pip-irc3DZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:11:43 INFO - Running setup.py (path:/tmp/pip-Pymdkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:11:43 INFO - Running setup.py (path:/tmp/pip-0MtM1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:11:43 INFO - Running setup.py (path:/tmp/pip-fBkiJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:11:43 INFO - Running setup.py (path:/tmp/pip-KT9nKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:11:43 INFO - Running setup.py (path:/tmp/pip-c6VmC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:43 INFO - Running setup.py (path:/tmp/pip-IfhPJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:11:43 INFO - Running setup.py (path:/tmp/pip-dGMwX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:11:43 INFO - Running setup.py (path:/tmp/pip-FEdFrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:11:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:11:43 INFO - Running setup.py install for manifestparser 09:11:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:11:43 INFO - Running setup.py install for mozcrash 09:11:43 INFO - Running setup.py install for mozdebug 09:11:43 INFO - Running setup.py install for mozdevice 09:11:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:11:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:11:44 INFO - Running setup.py install for mozfile 09:11:44 INFO - Running setup.py install for mozhttpd 09:11:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:11:44 INFO - Running setup.py install for mozinfo 09:11:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:11:44 INFO - Running setup.py install for mozInstall 09:11:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:11:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:11:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:11:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:11:44 INFO - Running setup.py install for mozleak 09:11:44 INFO - Running setup.py install for mozlog 09:11:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:11:44 INFO - Running setup.py install for moznetwork 09:11:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:11:44 INFO - Running setup.py install for mozprocess 09:11:44 INFO - Running setup.py install for mozprofile 09:11:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:11:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:11:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:11:44 INFO - Running setup.py install for mozrunner 09:11:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:11:45 INFO - Running setup.py install for mozscreenshot 09:11:45 INFO - Running setup.py install for moztest 09:11:45 INFO - Running setup.py install for mozversion 09:11:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:11:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:11:45 INFO - Cleaning up... 09:11:45 INFO - Return code: 0 09:11:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:45 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')]} 09:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:45 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')]} 09:11:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:45 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 0xe871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1124b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1122290>, '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 0x1122b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1123040>, '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': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', '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 09:11:45 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 09:11:45 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 09:11:45 INFO - Using env: {'DISPLAY': ':0', 09:11:45 INFO - 'HOME': '/home/cltbld', 09:11:45 INFO - 'LANG': 'en_US.UTF-8', 09:11:45 INFO - 'LANGUAGE': 'en_US:en', 09:11:45 INFO - 'LOGNAME': 'cltbld', 09:11:45 INFO - 'MAIL': '/var/mail/cltbld', 09:11:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:45 INFO - 'MOZ_NO_REMOTE': '1', 09:11:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:45 INFO - 'NO_EM_RESTART': '1', 09:11:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:45 INFO - 'PWD': '/builds/slave/test', 09:11:45 INFO - 'SHELL': '/bin/bash', 09:11:45 INFO - 'SHLVL': '1', 09:11:45 INFO - 'TERM': 'linux', 09:11:45 INFO - 'TMOUT': '86400', 09:11:45 INFO - 'USER': 'cltbld', 09:11:45 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', 09:11:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:45 INFO - '_': '/tools/buildbot/bin/python'} 09:11:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:11:45 INFO - Running setup.py (path:/tmp/pip-ufM81e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:11:45 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)) 09:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:11:45 INFO - Running setup.py (path:/tmp/pip-bBlWum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:11:45 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)) 09:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:11:45 INFO - Running setup.py (path:/tmp/pip-R3yZOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:11:45 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)) 09:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:11:45 INFO - Running setup.py (path:/tmp/pip-UbxyyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:11:45 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)) 09:11:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:11:45 INFO - Running setup.py (path:/tmp/pip-qyKH4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:11:46 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)) 09:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:11:46 INFO - Running setup.py (path:/tmp/pip-PMr1ET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:11:46 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)) 09:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:11:46 INFO - Running setup.py (path:/tmp/pip-GqqlnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:11:46 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)) 09:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:11:46 INFO - Running setup.py (path:/tmp/pip-XoJxRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:11:46 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)) 09:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:11:46 INFO - Running setup.py (path:/tmp/pip-mVI85g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:11:46 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)) 09:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:11:46 INFO - Running setup.py (path:/tmp/pip-F62P_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:11:46 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)) 09:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:11:46 INFO - Running setup.py (path:/tmp/pip-uru4de-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:11:46 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)) 09:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:11:46 INFO - Running setup.py (path:/tmp/pip-WDZLNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:11:46 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)) 09:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:11:46 INFO - Running setup.py (path:/tmp/pip-HHBv7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:11:46 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)) 09:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:11:46 INFO - Running setup.py (path:/tmp/pip-nUpe2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:11:46 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)) 09:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:46 INFO - Running setup.py (path:/tmp/pip-YhWAge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:46 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)) 09:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:11:46 INFO - Running setup.py (path:/tmp/pip-ldy5tg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:11:46 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)) 09:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:11:46 INFO - Running setup.py (path:/tmp/pip-SsyaoW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:11:46 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)) 09:11:46 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)) 09:11:46 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)) 09:11:46 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)) 09:11:46 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)) 09:11:46 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)) 09:11:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:11:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:48 INFO - Downloading blessings-1.6.tar.gz 09:11:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:11:49 INFO - Installing collected packages: blessings 09:11:49 INFO - Running setup.py install for blessings 09:11:49 INFO - Successfully installed blessings 09:11:49 INFO - Cleaning up... 09:11:49 INFO - Return code: 0 09:11:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:11:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:49 INFO - Reading from file tmpfile_stdout 09:11:49 INFO - Current package versions: 09:11:49 INFO - argparse == 1.2.1 09:11:49 INFO - blessings == 1.6 09:11:49 INFO - blobuploader == 1.2.4 09:11:49 INFO - docopt == 0.6.1 09:11:49 INFO - functools32 == 3.2.3-2 09:11:49 INFO - jsonschema == 2.5.1 09:11:49 INFO - manifestparser == 1.1 09:11:49 INFO - mozInstall == 1.12 09:11:49 INFO - mozcrash == 1.0 09:11:49 INFO - mozdebug == 0.1 09:11:49 INFO - mozdevice == 0.49 09:11:49 INFO - mozfile == 1.2 09:11:49 INFO - mozhttpd == 0.7 09:11:49 INFO - mozinfo == 0.9 09:11:49 INFO - mozleak == 0.1 09:11:49 INFO - mozlog == 3.4 09:11:49 INFO - moznetwork == 0.27 09:11:49 INFO - mozprocess == 0.25 09:11:49 INFO - mozprofile == 0.28 09:11:49 INFO - mozrunner == 6.13 09:11:49 INFO - mozscreenshot == 0.1 09:11:49 INFO - mozsystemmonitor == 0.3 09:11:49 INFO - moztest == 0.8 09:11:49 INFO - mozversion == 1.4 09:11:49 INFO - psutil == 3.1.1 09:11:49 INFO - requests == 1.2.3 09:11:49 INFO - wsgiref == 0.1.2 09:11:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:49 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')]} 09:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:49 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')]} 09:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:49 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 0xe871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1124b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1122290>, '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 0x1122b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1123040>, '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': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', '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 09:11:49 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 09:11:49 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 09:11:49 INFO - Using env: {'DISPLAY': ':0', 09:11:49 INFO - 'HOME': '/home/cltbld', 09:11:49 INFO - 'LANG': 'en_US.UTF-8', 09:11:49 INFO - 'LANGUAGE': 'en_US:en', 09:11:49 INFO - 'LOGNAME': 'cltbld', 09:11:49 INFO - 'MAIL': '/var/mail/cltbld', 09:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:49 INFO - 'MOZ_NO_REMOTE': '1', 09:11:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:49 INFO - 'NO_EM_RESTART': '1', 09:11:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:49 INFO - 'PWD': '/builds/slave/test', 09:11:49 INFO - 'SHELL': '/bin/bash', 09:11:49 INFO - 'SHLVL': '1', 09:11:49 INFO - 'TERM': 'linux', 09:11:49 INFO - 'TMOUT': '86400', 09:11:49 INFO - 'USER': 'cltbld', 09:11:49 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', 09:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:49 INFO - '_': '/tools/buildbot/bin/python'} 09:11:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:49 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)) 09:11:49 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)) 09:11:49 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)) 09:11:49 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)) 09:11:49 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)) 09:11:49 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)) 09:11:49 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)) 09:11:49 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)) 09:11:49 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)) 09:11:49 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)) 09:11:49 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)) 09:11:49 INFO - Cleaning up... 09:11:49 INFO - Return code: 0 09:11:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:11:49 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')]} 09:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:49 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')]} 09:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:49 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 0xe871f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf7fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1124b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1122290>, '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 0x1122b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1123040>, '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': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', '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 09:11:49 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 09:11:49 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 09:11:49 INFO - Using env: {'DISPLAY': ':0', 09:11:49 INFO - 'HOME': '/home/cltbld', 09:11:49 INFO - 'LANG': 'en_US.UTF-8', 09:11:49 INFO - 'LANGUAGE': 'en_US:en', 09:11:49 INFO - 'LOGNAME': 'cltbld', 09:11:49 INFO - 'MAIL': '/var/mail/cltbld', 09:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:49 INFO - 'MOZ_NO_REMOTE': '1', 09:11:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:49 INFO - 'NO_EM_RESTART': '1', 09:11:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:49 INFO - 'PWD': '/builds/slave/test', 09:11:49 INFO - 'SHELL': '/bin/bash', 09:11:49 INFO - 'SHLVL': '1', 09:11:49 INFO - 'TERM': 'linux', 09:11:49 INFO - 'TMOUT': '86400', 09:11:49 INFO - 'USER': 'cltbld', 09:11:49 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', 09:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:49 INFO - '_': '/tools/buildbot/bin/python'} 09:11:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:11:49 INFO - Cleaning up... 09:11:49 INFO - Return code: 0 09:11:49 INFO - Running post-action listener: _resource_record_post_action 09:11:49 INFO - Running post-action listener: _start_resource_monitoring 09:11:49 INFO - Starting resource monitoring. 09:11:49 INFO - [mozharness: 2017-03-03 17:11:49.966513Z] Finished create-virtualenv step (success) 09:11:49 INFO - [mozharness: 2017-03-03 17:11:49.966971Z] Running install step. 09:11:49 INFO - Running pre-action listener: _resource_record_pre_action 09:11:49 INFO - Running main action method: install 09:11:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:50 INFO - Reading from file tmpfile_stdout 09:11:50 INFO - Detecting whether we're running mozinstall >=1.0... 09:11:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:11:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:11:50 INFO - Reading from file tmpfile_stdout 09:11:50 INFO - Output received: 09:11:50 INFO - Usage: mozinstall [options] installer 09:11:50 INFO - Options: 09:11:50 INFO - -h, --help show this help message and exit 09:11:50 INFO - -d DEST, --destination=DEST 09:11:50 INFO - Directory to install application into. [default: 09:11:50 INFO - "/builds/slave/test"] 09:11:50 INFO - --app=APP Application being installed. [default: firefox] 09:11:50 INFO - mkdir: /builds/slave/test/build/application 09:11:50 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'] 09:11:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:12:06 INFO - Reading from file tmpfile_stdout 09:12:06 INFO - Output received: 09:12:06 INFO - /builds/slave/test/build/application/firefox/firefox 09:12:06 INFO - Running post-action listener: _resource_record_post_action 09:12:06 INFO - [mozharness: 2017-03-03 17:12:06.946779Z] Finished install step (success) 09:12:06 INFO - [mozharness: 2017-03-03 17:12:06.947054Z] Running run-tests step. 09:12:06 INFO - Running pre-action listener: _resource_record_pre_action 09:12:06 INFO - Running main action method: run_tests 09:12:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:12:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:12:06 INFO - Python 2.7.3 09:12:06 INFO - Return code: 0 09:12:06 INFO - grabbing minidump binary from tooltool 09:12: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')]} 09:12:06 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 0x1122290>, '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 0x1122b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1123040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:12:06 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 09:12:06 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 09:12:06 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 09:12:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:12:07 INFO - Return code: 0 09:12:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:12:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:12:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:12:07 INFO - ENV: RUST_BACKTRACE is now 1 09:12:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:12:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:12:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:12:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-024 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:12:07 INFO - Using env: {'DISPLAY': ':0', 09:12:07 INFO - 'HOME': '/home/cltbld', 09:12:07 INFO - 'LANG': 'en_US.UTF-8', 09:12:07 INFO - 'LANGUAGE': 'en_US:en', 09:12:07 INFO - 'LOGNAME': 'cltbld', 09:12:07 INFO - 'MAIL': '/var/mail/cltbld', 09:12:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:12:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:12:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:07 INFO - 'MOZ_NO_REMOTE': '1', 09:12:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:12:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:07 INFO - 'NO_EM_RESTART': '1', 09:12:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:07 INFO - 'PWD': '/builds/slave/test', 09:12:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:12:07 INFO - 'RUST_BACKTRACE': '1', 09:12:07 INFO - 'SHELL': '/bin/bash', 09:12:07 INFO - 'SHLVL': '1', 09:12:07 INFO - 'TERM': 'linux', 09:12:07 INFO - 'TMOUT': '86400', 09:12:07 INFO - 'USER': 'cltbld', 09:12:07 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389', 09:12:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:07 INFO - '_': '/tools/buildbot/bin/python'} 09:12:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:12:07 INFO - mozversion application_buildid: 20170303161910 09:12:07 INFO - mozversion application_changeset: 86e1748ab478e23f7a73e2c4af11a310d26fbda9 09:12:07 INFO - mozversion application_display_name: Nightly 09:12:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:12:07 INFO - mozversion application_name: Firefox 09:12:07 INFO - mozversion application_remotingname: firefox 09:12:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 09:12:07 INFO - mozversion application_vendor: Mozilla 09:12:07 INFO - mozversion application_version: 54.0a1 09:12:07 INFO - mozversion platform_buildid: 20170303161910 09:12:07 INFO - mozversion platform_changeset: 86e1748ab478e23f7a73e2c4af11a310d26fbda9 09:12:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 09:12:07 INFO - mozversion platform_version: 54.0a1 09:12:07 INFO - using testdate: 1488561127 09:12:07 INFO - actual date: 1488561127 09:12:07 INFO - starting webserver on 'localhost:58136' 09:12:07 INFO - SUITE-START | Running 2 tests 09:12:07 INFO - TEST-START | dromaeo_css 09:12:07 INFO - operating with platform_type : linux_ 09:12:07 INFO - Initialising browser for dromaeo_css test... 09:12:07 INFO - TEST-INFO | started process 26699 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcNUYuQ/profile http://localhost:58136/getInfo.html) 09:12:10 INFO - PROCESS | 26699 | __metrics Screen width/height:1600/1200 09:12:10 INFO - PROCESS | 26699 | colorDepth:24 09:12:10 INFO - PROCESS | 26699 | Browser inner width/height: 1024/697 09:12:10 INFO - PROCESS | 26699 | __metrics 09:12:11 INFO - PROCESS | 26699 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:12:11 INFO - PROCESS | 26699 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:12:12 INFO - TEST-INFO | 26699: exit 0 09:12:12 INFO - Browser initialized. 09:12:12 INFO - Running cycle 1/1 for dromaeo_css test... 09:12:12 INFO - TEST-INFO | started process 26787 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcNUYuQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:12:24 INFO - PROCESS | 26787 | RSS: Main: 143208448 09:12:24 INFO - PROCESS | 26787 | 09:15:27 INFO - PROCESS | 26787 | Cycle 1(1): loaded http://localhost:58136/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58136/tests/dromaeo/cssquery-ext.html) 09:15:28 INFO - PROCESS | 26787 | RSS: Main: 305979392 09:15:28 INFO - PROCESS | 26787 | 09:17:14 INFO - PROCESS | 26787 | ************************* 09:17:14 INFO - PROCESS | 26787 | A coding exception was thrown and uncaught in a Task. 09:17:14 INFO - PROCESS | 26787 | 09:17:14 ERROR - PROCESS | 26787 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:17:14 INFO - PROCESS | 26787 | Full stack: 09:17:14 INFO - PROCESS | 26787 | ************************* 09:18:31 INFO - PROCESS | 26787 | Cycle 1(1): loaded http://localhost:58136/tests/dromaeo/cssquery-ext.html (next: http://localhost:58136/tests/dromaeo/cssquery-jquery.html) 09:18:31 INFO - PROCESS | 26787 | RSS: Main: 236179456 09:18:31 INFO - PROCESS | 26787 | 09:21:33 INFO - PROCESS | 26787 | Cycle 1(1): loaded http://localhost:58136/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58136/tests/dromaeo/cssquery-mootools.html) 09:21:34 INFO - PROCESS | 26787 | RSS: Main: 173924352 09:21:34 INFO - PROCESS | 26787 | 09:24:36 INFO - PROCESS | 26787 | Cycle 1(1): loaded http://localhost:58136/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58136/tests/dromaeo/cssquery-prototype.html) 09:24:37 INFO - PROCESS | 26787 | RSS: Main: 206942208 09:24:37 INFO - PROCESS | 26787 | 09:27:40 INFO - PROCESS | 26787 | Cycle 1(1): loaded http://localhost:58136/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58136/tests/dromaeo/cssquery-yui.html) 09:27:40 INFO - PROCESS | 26787 | RSS: Main: 179257344 09:27:40 INFO - PROCESS | 26787 | 09:30:43 INFO - PROCESS | 26787 | Cycle 1(1): loaded http://localhost:58136/tests/dromaeo/cssquery-yui.html (next: http://localhost:58136/tests/dromaeo/cssquery-dojo.html) 09:30:43 INFO - PROCESS | 26787 | RSS: Main: 279556096 09:30:43 INFO - PROCESS | 26787 | 09:30:43 INFO - PROCESS | 26787 | __start_tp_report 09:30:43 INFO - PROCESS | 26787 | _x_x_mozilla_page_load 09:30:43 INFO - PROCESS | 26787 | _x_x_mozilla_page_load_details 09:30:43 INFO - PROCESS | 26787 | |i|pagename|runs| 09:30:43 INFO - PROCESS | 26787 | |0;dojo.html;3217.1957010747315;3247.464168412212;3253.202965273507;3286.474164133739;3298.868045278189;5362.973185134068;5431.266778984836;5614.887702245953;5696.316442026962;5713.485786279234;5576.33084029916;5663;5671.035923892938;5711.8000869969555;5726.226959360485;5134.204198349262;5149.407818100929;5168.948310516894;5195.038917800201;5213.843584692465;5182.844514664547;5245.763940622662;5252.317198764155;5255.842324730264;5283.894322113555;5016.29771831942;5043.899122017576;5124.8206312778875;5134.358205224347;5172.689638621695;5111.718855462948;5124.718140502271;5129.281900533928;5130.84607461773;5132.948670513321;5029.145045342264;5038.345015148007;5079.923801142986;5155.871103222412;5178.352705911761;5586.050371436865;5596.804111856106;5608.635438696471;5611.803586874481;5618.297712785902;5834.358220595731;6043.305019922721;6049;6056;6067.605605635619;5912.674802885839;5975.342712301644;5982.611130276518;5988.31134419543;5999.730012149464;5553.222548843165;5587.329520457531;5597.160425936101;5598.272224610774;5598.384177740445;6098.3414614785315;6101.186783078283;6107.552954153613;6134.938650613437;6136.20229370179;5880.94119058804;5911.113333000085;5913.201717768132;5942.58401911862;5989.790357337472;6115.127601665806;6122.184701511125;6128.961487065963;6133.958874026734;6136.1332873997535;6083.178770865877;6090.33006369299;6101.023836186278;6104.694765261719;6140.109684095781;5845.970770146122;5884.352721200665;5986.341502434729;6090;6101.938980610137;4605.815767369268;4616.538346165356;4623.514530974267;4625.398698169284;4626.329182268552;4108.034611866209;4125.628693417607;4164.167166566699;4172.83308667656;4181.895452613649;5658.434156584391;5664.178694089416;5666.206731057655;5671.347795003628;5674.26234589509;5957.344692083867;5991.910121348184;6061.6666083365835;6106.511479081662;6115.021596544531;5501.339839219319;5551.861203469864;5626.352969408488;5644.096944488862;5648.096304591246;5076.923846142311;5132.5124113209185;5133.435322114565;5140.151874940593;5154.690718556899;6203.348648391854;6230.252369715663;6244.531660125418;6252.124702541648;6254.937450625435;5940.257467816523;6006.069059295816;6014.0678194879865;6028.57799953999;6029.095635654687;6196.597221180609;6229.314775374796;6254.2807577129215;6356.55504114726;6366.6180029198395;6016.097585362056;6028.57799953999;6029.558617934751;6032.155498230339;6081.817545473643;6100.054491553817;6146.907796383147;6152.907706384497;6163.753449861955;6167.1366008759705;5814.215080964016;5848.502877255481;5934.080217566284;5938.673372964356;5953.74971256041;5900.262467191601;5943.1679564861815;6027.336992930862;6033.064874944391;6042.214512113397;5959.314678811992;5968.164456976114;5968.671723055274;5982.282126144891;5982.670953097621;5945.256842894638;5961.254843144607;6029.909551356821;6032.728527216198;6036;5647.6893770843;5680.914786278292;5739.0243658578975;5760.279965004374;5761.107028410603 09:30:43 INFO - PROCESS | 26787 | |1;ext.html;707.1408238989627;758.8065043413931;784.0787075186656;840.625921464948;863.2101627011285;30022.24944376389;30337.241568960788;41123.79438102813;41595.58404415955;41841.37237941433;41568.58431415685;41840.163196736044;41859.58140418595;41863.79068104663;41914.37128443076;8733.170348816851;8868.512231827246;8870.246029087502;8873.866891996626;8880.333974951873;7264.4914855960105;7404.111506619213;8680.52257125861;10032.648857289996;10192.541335639877;10317.535710892991;10328.896711032869;10329.948350258239;10335.121514671262;10355.11981481575;10183.490825458697;10203.591856325738;10235.130013948748;10237.283390162655;10263.846042309371;10076.748081297952;10142.478628205754;10143.89856101437;10150.390976541432;10154.746131346701;39013.80493097545;39157.608423915684;39210.21579568421;39267.41098883519;39320;3817.8091095445393;3843.423486477023;3846.6345697158727;3900.8829735107706;3906.667933225651;25697.486050279076;25760;25837.095701650353;25910.87044564765;26299.868500657565;4058.655014323786;4095.8361665533344;4130.091379896418;4174.102567947906;4197.244495990721;204335;204802;205072;205605;207218;42070.36894446586;42279.365809512885;42319.57680423218;42327.57672423298;42373.36439953403;43789.56210437918;43870;43883.85626012361;43885.56114438878;43981.12037759291;12847.871521284855;12989.480420783251;13075.084744067824;13105.14816536922;13156.473741050251;10910.61812836547;10920.344779313267;10921.890781092088;10934.45327733626;10970.122390208764;10843.457827108614;11029.227954043301;11928.403579820973;12066.155369124253;12162.148649594443;5353.197020446964;5381.730913454312;5400.62195646301;5404.83879823703;5444.945550544523;942.4062840410497;965.9471176417597;965.971020869375;967.431142542131;967.6274634265718;25339.239822805346;26554.6016809748;26768;27057.729422705914;27221;1010.9898901011043;1025.6922923123034;1029.7837454134565;1033.6743925663393;1040.7606250562262;13607.86392136066;14379.352929118215;14464.421423142956;14561.126332419877;14645.267736613127;288883;291741;291942;291996;292016;46549.767251163525;47380.52619473761;53215.99999999923;53333.466665333624;53501;1352.1684164239118;1399.5801259622276;1408.4929425406845;1412.6256542016374;1413.4911431884514;26430.07494737675;26924.192274231806;27179.86410067937;27523.724762752117;27604.309892252855;35184;35394.292114157055;35419.29161416809;35421.82289088538;35446.759363422156;330598;331651;332387;332853;333479;22689.092436302362;22840.31479055631;23334.649980250648;24638.630420544065;24776.380590485383;30872;31162.376752465276;41293.793531032155;41552.79223603863;41768.164636707705;31588.802733081488;32972.67027329696;33187.50218746769;33573.49639755455;34170.65829341774;32420;32935.67064329326;33250.50124248186;33655.83172084124;33974.49038264379;33733.66266337403;34462.655373445945;34582.48126278057;34702.65297346994;34773.304533909686;26978.311475328057;28275.293117672223;29265.70734292715;29693.5545966815;29748.70251297546;23446.413839654146;23683.881580591988;23838.16566420167;23981.400464987815;24112.75887241105 09:30:43 INFO - PROCESS | 26787 | |2;jquery.html;425.67861264745113;427.70479640378824;428.83061190829625;436.95630436956327;439.5933761270825;18753.156107975155;18791.154398052087;20348.796512034874;20554.69167962482;20671.793282067192;20812.687809682866;21009.894950525264;21048.473788155305;21081.789182108176;21100.261490847824;6557.704903279352;6694.83262918426;6717.059611654372;6721.3950744433;6730.427913627347;6347.809565713036;6512;6582.078509008743;6606.042123892033;6675.165604299463;6431.6784160792;6658.800235992928;6674.332566743311;6675.732970681192;6676.866462670744;6611.504137189706;6657.966710166418;6665.100211471437;6669.399754022113;6689.665516724169;6244.3755624437645;6297.0554416837385;6424.229092508929;6438.678066096676;6450.129232553639;24260.393490162885;24357.756422435727;24442.022319107215;24597.631035534483;24672.383190420205;3332.450145725952;3406.114410253327;3491.5618104836326;3494.318607871466;3530.258645684409;15396.46112386061;15596.688066238725;15758;15765;15768.448104316409;2059.9588008239903;2198.670199470076;2221.911123555056;2255.875926824024;2282.657601359793;88173.55913220393;94122;94490;94563;94573.5271323639;17603.82396176022;17609.748539268632;17629;17741.11294435273;17932.55168620795;31691.84154079261;31753;31850.840745796122;31879.681203187673;32025;8736.432131911533;8898.42160259594;9035.141661542142;9225.953870230605;9243.075692430837;7055.9294407056295;7098.254683258287;7104.325089116525;7271.709131634781;7273.6726847292;9254;9524.476013856334;9598.136167744937;9613.855792163124;9802.157374361497;3305.9008229753144;3386.2211691310645;3477.930441391158;3711.3319602471815;3798.631573690223;1158.229777198154;1166.650004998497;1171.4142928535732;1181.173178774861;1189.6312143235625;18822.153003114618;20171.39485815428;20353.287634932705;20555.177792888117;20659.380218593466;932.664240873285;986.7483791633231;997.436448406648;1008.6923488336128;1167.5096459486865;10958.726031849268;11642.185047046794;11682.474288657031;11694;11698.824517632242;651887;658772;671612;672072.4490405944;673870;90631;110115.44942275397;112658.43670781594;113254.86745132608;113459;1128.4583399968062;1225.3811825028304;1252.1110011891658;1277;1340.4571148684797;18403.17185726619;19187.424377268704;19431.70852437243;19495.902520487307;19570;50674.746626266635;51157;51399;51728.53020201045;52115.21827172671;58592;59599.40400595938;60802;61523.384766151765;68146.318536814;11402.885971140182;11687.480627518367;11777;12119.212251203644;12199.451024704087;13662.590122296348;13840.723185536031;13875.375608097458;14035.157890526601;14540.49108281205;12188.20776649515;12190.329531875832;12212.206206596542;12354.320512371547;12679.683007924876;12010;12011.939940300243;12189.51241950312;12256.387180640753;12542.561010364594;12442.440090195782;12547.74904501923;12555.372231388577;12653.430595623404;12734.490620375082;12050.517979281081;12127.514899403925;12160.270383777002;12495.750084998068;12619.242845429304;12142.514299428278;12249.938750306192;12323.75352492963;12342.691432714253;12485.250884946932 09:30:43 INFO - PROCESS | 26787 | |3;mootools.html;1504.473434297996;1509.5848641623559;1518.1725959352152;1530.9081455112691;1535.6621543260483;10203.99999999999;10959.671209863696;10962.725931851708;10970.067544258738;10970.177236707248;10429.478526073704;10698.78602427951;11050.226484146113;11069.778604427907;11089.168312376563;2127.8297736181107;2161.23276236936;2163.4807646164886;2165.1880544795704;2166.620841352757;2137.2519618133606;2183.8471307008517;2184.912603495858;2185.846990710651;2185.9234926777567;2115.0270875397337;2133.0934352900035;2141.2612648636195;2145.4743587821004;2149.4948687058527;2072.347210628647;2091.173986275428;2092.435042538506;2092.8221101206414;2095.3818623506104;1942.5337918899504;2088.6658134698378;2108.4307237046064;2114.228306668055;2115.640341141994;10717.571297148104;10780.40707761073;10802.13582913373;10806.675799726021;10809.513571889283;5276.630635855492;5401.189821526802;5402.972985135049;5404.810831620914;5407.080796264644;3188.4260833050043;3369.6461871503384;3391.3386889556546;3400.421928272175;3407.335569563936;1266.1137203957176;1296.2352212194755;1313.166139501414;1375.3192169875877;1390.652336915771;13938.233397163152;14222.857771422257;14500.782488262685;15724.685506289925;15788.526344209693;4387.5393083726385;4483.618892394118;4483.618892394183;4513.074819661962;4525.162844873709;11959.760804783855;12020.63938081859;12032.578859739868;12038.638840834788;12056.578019769266;4740.288956656529;4878.243872199815;5261.579073674096;5469.480399362054;5476.397596264408;5083.74581270935;5100.56645185156;5100.591952643779;5103.413107492686;5119.180931051014;3656.8720094796554;3710.2950439416422;3722.739408241397;3767.4913886625504;3832.0611450194324;988.5749127875083;991.3803872579638;998.4358837256926;1001.789624178916;1007.189212683782;810.5379933437883;816.4856140631364;842.7766641839918;844.4595458906305;875.9562021898879;6406;6407.519436042316;6587.736490540325;6643.202815662152;6797.184337879387;1048.3028785857318;1055.7624534479835;1066.3655125200494;1084.9294795838246;1101.1521128730833;3774.509313789245;3843.8462461501226;3876.651101400831;3962.582824477124;3972.7616343019513;12957.935210323889;13257;13353.332333383292;13412.463501460028;13522.459101636021;13093.738125237442;13235.867641323464;13271.668208294677;13420.46318147263;13422.463101476027;9872.555734991942;9967.700968970943;9983.301168918106;10052.64815731446;10136.138428233682;6377.1390862233;6387.169667943138;6430.3891130342545;6446.290908000023;6594.637294948824;11557.942210288895;11893.643190704293;12016.098792590416;12052.216605920587;12130.454129564014;10487.475626218811;10700.678979630624;11358.375289358833;12431;12465.875341246472;8872.86690699627;9172.44965302084;9315.34792564514;9377.85933211016;9400.435973841591;9713;10111.443870587189;10136.391816491034;10216.693499195037;10228.181745460493;8945.418547794372;9029.051949545203;9049.411788233743;9172.495512746864;9269.073092690676;8843.557822108998;8862.246709029547;8903.554822258731;8910.331725120512;8992.775180620276;7765.650545725343;7892.092409372766;7940.801479963047;7954.522728636301;7976.202379761978;5820.679862607597;5828.970855145867;5900.468957793819;5900.616459930091;6083.27000759894;5975.282966044103;5991.460768530853;6036.577439579363;6124.724387402666;6145.385461453819 09:30:43 INFO - PROCESS | 26787 | |4;prototype.html;396.8610986154844;399.28527934996356;420.23936674618943;422.79283151255885;426.59047314578015;5020.39755229373;5126.897462050757;5158.587313014959;5162.967406518693;5247.763850626721;5042.798288068482;5111.463296353885;5134.768935397907;5141.511556402145;5144.922826157611;4482.596566309024;4509.880356232582;4519.27691569349;4529.23003089474;4547.840825571106;4383.802728877192;4493.326001099828;4535.523770004152;4546.249868771649;4546.522615125414;4448.399466072087;4466.397036400077;4482.910341793162;4527.162474942146;4562.771861406933;4432.445944256968;4495.505494395647;4500.932486012729;4516.554702495203;4573.176828170928;4123.793810309502;4270.850520231777;4404.427424434835;4406.008648054194;4413.889652758675;5028.586278586263;5032.421271553745;5039.244113382985;5080.542751152414;5084.56781173597;5159.535641792257;5160.251763494272;5161.948380516184;5171.327727395452;5234.398044224887;5290.418054014055;5458.645188062763;5467.863303417406;5472.808451704172;5507.697076660782;4738.3129446230105;4748.358971538867;4752;4755.478246960974;4772.80908763649;5752.993226185401;5765.913511297334;5773.797917072882;5813.337597313707;5863.677497737623;4737.218358970731;4919.77860996256;4951.306817045616;4969.950300497021;5013.298138260647;5000.974800165957;5019;5020.949790502048;5050.898982020339;5064.7214403207445;5142.280080788692;5154.479428568581;5167.431582525919;5217.217417387422;5241.371035475691;5137.280780690707;5396.730163491809;5426.9185962210595;5432.0123931838125;5527.005139074925;1524.839891811366;1554.7745576891286;1558.5636021913883;1559.3762495001863;1561.7657351397154;1228.9201201921846;1238.9380530973415;1251.4493622805937;1258.9181703189263;1260.9093134438644;5173.430922598511;5214.9478505214465;5241.42344342122;5258.894822103536;5264.5525130363585;5459.617826752089;5529.7650874239025;5601.87128891027;5614.775408983595;5632.436756324335;5214.765335559909;5221.12837939132;5227.059129356752;5234.685918844882;5263.631545791758;2855.4574630820493;2947.2189869684553;2953.704629537029;2953.896613618513;2957.6598691150793;5730.770769169187;5749.568782341341;5760.855978600569;5771.307443106854;5774;5637.548996080303;5645.4031145672625;5693.572982026365;5698.914516282259;5754.798582049608;5452.59105567084;5702.8289151325525;5749.06797533261;5777.94222057774;5785.450382213767;5581.218629391969;5611.426687249878;5613.438656134517;5620.4098569649595;5638.520725738358;5251.238570407346;5450.536704380013;5482.862928426662;5574.77700891976;5612.102063669793;5772.538196944319;5785.27684039495;5802.216700745515;5822.088955522239;5841.977653910463;4643.883902902455;4647.907041859212;4652.674312798072;4666.906661866811;4684.227102528044;5419.891602167856;5695.800646977336;5704.2014118024335;5708.885822283448;5719.971400142973;5506.862328441821;5512.3660779010925;5533.750981205936;5559.6664200148125;5578.581606379456;5362.383325917569;5404.891902161856;5476.452354764491;5517.475839795213;5538.061678412523;5085.92371114441;5388.488093631099;5419.783208671609;5422.8644283891645;5427.430119837518;5472.124460086367;5510.256115424527;5514;5516.386766667839;5555.166724991283;5205.791768329224;5400.729963501731;5410.756515956713;5453.182022696627;5474.945250547603 09:30:43 INFO - PROCESS | 26787 | |5;yui.html;800.5036877136173;807.2008711375739;849.2950850793842;874.1040433555609;876.3909083187185;15242.695146097072;15340.232988350579;15513.146776927264;15781.447649332276;16006.83993160068;15023.774643380359;15258.542243732705;15265.542033739006;15274.38902443901;15294.388224471035;819.6270696832956;835.5571547080027;856.9442986205908;860.5095095795399;868.2186032570673;828.1139181076251;853.4623187391966;860.9182127697859;861.5046348349724;861.5864385095158;908.0374802709116;908.4095338030268;909.7179874238974;911.0434044253541;911.7538264668535;902.2736696958946;902.7652810269311;903.1059251341158;905.180811365715;905.2305540290766;669.2203582826002;669.4209508774875;669.6685140855259;669.7756251655701;669.9263081061129;14376.784348234785;14421.206833624146;14434.206118663575;14658.853411465856;14968.02707823988;14707.411703531845;15188.620284492865;15387.53837384869;15505.689886202326;16089.19554022306;12312.753744925141;12739.936300318532;12741.362931853462;12776.744465110645;13224.867751322556;3678.6321367863266;3692.649198326155;3699.8705045323554;3711.554613446404;3774.830132644038;84159;84973;87501;88173.55913220393;88981;11716.121290903217;11737.119716021332;11739.23694959825;11945.88054119465;11963.92324690782;22707.772922270895;23066.192683256006;23077;23130.190443334403;23385.649215261783;7899.68401263943;7994.720184793504;8025.9197408025175;8132.796680083045;8264.958675206586;4432.468103827561;4432.889177770517;4486.798094085774;4641.930371044437;4700.318453824177;3343.6990670839255;3434.6221915589267;3633.4731463937583;3747.475353450519;3821.6942644588366;623.2614351992903;623.4420193926411;626.1484381241507;644.4650939720021;645.5481163185694;713.2724620886667;729.3800269770652;746.5371469688828;750;758.6586036283694;10089.091981721533;10282.794344113076;10529.84205236922;11050.33697978124;11123;800.5036877136097;804.4650307545534;809.7165991902905;815.6370415165313;816.0452270843127;4867.318575399447;5287.920681189786;5405.135278302506;5443.8094666686475;5470.261514695465;67507.66246168737;75243.62378188074;75968;77768.61115694499;77788.61105694436;52113;65141.34858651353;65809.34190658128;65905;65938.67030664816;58312.70843645755;58833.41166588365;58939.705301473216;59182.704086479294;59311;10130.696079117639;10149.470222690336;10311.123554497952;10320.329178603366;10324.741881453023;39444.40833387444;40745.38881916833;40906.79546602248;41295.1740965173;41748.58251417569;46043.539564603925;47169;47674.28488572744;51545.48454515407;52416.73791631017;13424.731505370033;13610.251436171106;13612.38744256491;13630.591082267549;13631.522896698569;14516.274186290431;14613.415463381345;15551.922240388725;15622.927853143536;16188.190590470665;14391.568252952427;14756.409743610564;14777.758496740042;14790.482333118289;16165.757513637069;14478.348474318891;14916.552503424915;14984.40062397492;15384.153871537173;15595.220238987778;13083.476660933457;13121.540746073842;13429.865701342862;13600.65998350049;14125.43498260058;11272.436378180893;11475;11808.82286765682;12028.398580071136;12078.637640870787;11937.522499100287;12080.75838483243;12090.939545302217;12188.329641869783;12194.81707774402 09:30:43 INFO - PROCESS | 26787 | __end_tp_report 09:30:43 INFO - PROCESS | 26787 | __start_cc_report 09:30:43 INFO - PROCESS | 26787 | _x_x_mozilla_cycle_collect,1603 09:30:43 INFO - PROCESS | 26787 | __end_cc_report 09:30:43 INFO - PROCESS | 26787 | __startTimestamp1488562243695__endTimestamp 09:30:43 INFO - PROCESS | 26787 | 09:30:43 INFO - PROCESS | 26787 | ------- Summary: start ------- 09:30:43 INFO - PROCESS | 26787 | Number of tests: 6 09:30:43 INFO - PROCESS | 26787 | 09:30:43 INFO - PROCESS | 26787 | [#0] dojo.html Cycles:165 Average:5610.57 Median:5845.97 stddev:641.97 (11.0%) stddev-sans-first:615.87 09:30:43 INFO - PROCESS | 26787 | Values: 3217.2 3247.5 3253.2 3286.5 3298.9 5363.0 5431.3 5614.9 5696.3 5713.5 5576.3 5663.0 5671.0 5711.8 5726.2 5134.2 5149.4 5168.9 5195.0 5213.8 5182.8 5245.8 5252.3 5255.8 5283.9 5016.3 5043.9 5124.8 5134.4 5172.7 5111.7 5124.7 5129.3 5130.8 5132.9 5029.1 5038.3 5079.9 5155.9 5178.4 5586.1 5596.8 5608.6 5611.8 5618.3 5834.4 6043.3 6049.0 6056.0 6067.6 5912.7 5975.3 5982.6 5988.3 5999.7 5553.2 5587.3 5597.2 5598.3 5598.4 6098.3 6101.2 6107.6 6134.9 6136.2 5880.9 5911.1 5913.2 5942.6 5989.8 6115.1 6122.2 6129.0 6134.0 6136.1 6083.2 6090.3 6101.0 6104.7 6140.1 5846.0 5884.4 5986.3 6090.0 6101.9 4605.8 4616.5 4623.5 4625.4 4626.3 4108.0 4125.6 4164.2 4172.8 4181.9 5658.4 5664.2 5666.2 5671.3 5674.3 5957.3 5991.9 6061.7 6106.5 6115.0 5501.3 5551.9 5626.4 5644.1 5648.1 5076.9 5132.5 5133.4 5140.2 5154.7 6203.3 6230.3 6244.5 6252.1 6254.9 5940.3 6006.1 6014.1 6028.6 6029.1 6196.6 6229.3 6254.3 6356.6 6366.6 6016.1 6028.6 6029.6 6032.2 6081.8 6100.1 6146.9 6152.9 6163.8 6167.1 5814.2 5848.5 5934.1 5938.7 5953.7 5900.3 5943.2 6027.3 6033.1 6042.2 5959.3 5968.2 5968.7 5982.3 5982.7 5945.3 5961.3 6029.9 6032.7 6036.0 5647.7 5680.9 5739.0 5760.3 5761.1 09:30:43 INFO - PROCESS | 26787 | 09:30:43 INFO - PROCESS | 26787 | [#1] ext.html Cycles:180 Average:42471.17 Median:31375.59 stddev:73757.78 (235.1%) stddev-sans-first:73898.04 09:30:43 INFO - PROCESS | 26787 | Values: 707.1 758.8 784.1 840.6 863.2 30022.2 30337.2 41123.8 41595.6 41841.4 41568.6 41840.2 41859.6 41863.8 41914.4 8733.2 8868.5 8870.2 8873.9 8880.3 7264.5 7404.1 8680.5 10032.6 10192.5 10317.5 10328.9 10329.9 10335.1 10355.1 10183.5 10203.6 10235.1 10237.3 10263.8 10076.7 10142.5 10143.9 10150.4 10154.7 39013.8 39157.6 39210.2 39267.4 39320.0 3817.8 3843.4 3846.6 3900.9 3906.7 25697.5 25760.0 25837.1 25910.9 26299.9 4058.7 4095.8 4130.1 4174.1 4197.2 204335.0 204802.0 205072.0 205605.0 207218.0 42070.4 42279.4 42319.6 42327.6 42373.4 43789.6 43870.0 43883.9 43885.6 43981.1 12847.9 12989.5 13075.1 13105.1 13156.5 10910.6 10920.3 10921.9 10934.5 10970.1 10843.5 11029.2 11928.4 12066.2 12162.1 5353.2 5381.7 5400.6 5404.8 5444.9 942.4 965.9 966.0 967.4 967.6 25339.2 26554.6 26768.0 27057.7 27221.0 1011.0 1025.7 1029.8 1033.7 1040.8 13607.9 14379.4 14464.4 14561.1 14645.3 288883.0 291741.0 291942.0 291996.0 292016.0 46549.8 47380.5 53216.0 53333.5 53501.0 1352.2 1399.6 1408.5 1412.6 1413.5 26430.1 26924.2 27179.9 27523.7 27604.3 35184.0 35394.3 35419.3 35421.8 35446.8 330598.0 331651.0 332387.0 332853.0 333479.0 22689.1 22840.3 23334.6 24638.6 24776.4 30872.0 31162.4 41293.8 41552.8 41768.2 31588.8 32972.7 33187.5 33573.5 34170.7 32420.0 32935.7 33250.5 33655.8 33974.5 33733.7 34462.7 34582.5 34702.7 34773.3 26978.3 28275.3 29265.7 29693.6 29748.7 23446.4 23683.9 23838.2 23981.4 24112.8 09:30:43 INFO - PROCESS | 26787 | 09:30:43 INFO - PROCESS | 26787 | [#2] jquery.html Cycles:180 Average:36683.17 Median:13271.53 stddev:109225.36 (823.0%) stddev-sans-first:109497.83 09:30:43 INFO - PROCESS | 26787 | Values: 425.7 427.7 428.8 437.0 439.6 18753.2 18791.2 20348.8 20554.7 20671.8 20812.7 21009.9 21048.5 21081.8 21100.3 6557.7 6694.8 6717.1 6721.4 6730.4 6347.8 6512.0 6582.1 6606.0 6675.2 6431.7 6658.8 6674.3 6675.7 6676.9 6611.5 6658.0 6665.1 6669.4 6689.7 6244.4 6297.1 6424.2 6438.7 6450.1 24260.4 24357.8 24442.0 24597.6 24672.4 3332.5 3406.1 3491.6 3494.3 3530.3 15396.5 15596.7 15758.0 15765.0 15768.4 2060.0 2198.7 2221.9 2255.9 2282.7 88173.6 94122.0 94490.0 94563.0 94573.5 17603.8 17609.7 17629.0 17741.1 17932.6 31691.8 31753.0 31850.8 31879.7 32025.0 8736.4 8898.4 9035.1 9226.0 9243.1 7055.9 7098.3 7104.3 7271.7 7273.7 9254.0 9524.5 9598.1 9613.9 9802.2 3305.9 3386.2 3477.9 3711.3 3798.6 1158.2 1166.7 1171.4 1181.2 1189.6 18822.2 20171.4 20353.3 20555.2 20659.4 932.7 986.7 997.4 1008.7 1167.5 10958.7 11642.2 11682.5 11694.0 11698.8 651887.0 658772.0 671612.0 672072.4 673870.0 90631.0 110115.4 112658.4 113254.9 113459.0 1128.5 1225.4 1252.1 1277.0 1340.5 18403.2 19187.4 19431.7 19495.9 19570.0 50674.7 51157.0 51399.0 51728.5 52115.2 58592.0 59599.4 60802.0 61523.4 68146.3 11402.9 11687.5 11777.0 12119.2 12199.5 13662.6 13840.7 13875.4 14035.2 14540.5 12188.2 12190.3 12212.2 12354.3 12679.7 12010.0 12011.9 12189.5 12256.4 12542.6 12442.4 12547.7 12555.4 12653.4 12734.5 12050.5 12127.5 12160.3 12495.8 12619.2 12142.5 12249.9 12323.8 12342.7 12485.3 09:30:43 INFO - PROCESS | 26787 | 09:30:43 INFO - PROCESS | 26787 | [#3] mootools.html Cycles:180 Average:6583.47 Median:2185.38 stddev:4175.91 (191.1%) stddev-sans-first:4170.19 09:30:43 INFO - PROCESS | 26787 | Values: 1504.5 1509.6 1518.2 1530.9 1535.7 10204.0 10959.7 10962.7 10970.1 10970.2 10429.5 10698.8 11050.2 11069.8 11089.2 2127.8 2161.2 2163.5 2165.2 2166.6 2137.3 2183.8 2184.9 2185.8 2185.9 2115.0 2133.1 2141.3 2145.5 2149.5 2072.3 2091.2 2092.4 2092.8 2095.4 1942.5 2088.7 2108.4 2114.2 2115.6 10717.6 10780.4 10802.1 10806.7 10809.5 5276.6 5401.2 5403.0 5404.8 5407.1 3188.4 3369.6 3391.3 3400.4 3407.3 1266.1 1296.2 1313.2 1375.3 1390.7 13938.2 14222.9 14500.8 15724.7 15788.5 4387.5 4483.6 4483.6 4513.1 4525.2 11959.8 12020.6 12032.6 12038.6 12056.6 4740.3 4878.2 5261.6 5469.5 5476.4 5083.7 5100.6 5100.6 5103.4 5119.2 3656.9 3710.3 3722.7 3767.5 3832.1 988.6 991.4 998.4 1001.8 1007.2 810.5 816.5 842.8 844.5 876.0 6406.0 6407.5 6587.7 6643.2 6797.2 1048.3 1055.8 1066.4 1084.9 1101.2 3774.5 3843.8 3876.7 3962.6 3972.8 12957.9 13257.0 13353.3 13412.5 13522.5 13093.7 13235.9 13271.7 13420.5 13422.5 9872.6 9967.7 9983.3 10052.6 10136.1 6377.1 6387.2 6430.4 6446.3 6594.6 11557.9 11893.6 12016.1 12052.2 12130.5 10487.5 10700.7 11358.4 12431.0 12465.9 8872.9 9172.4 9315.3 9377.9 9400.4 9713.0 10111.4 10136.4 10216.7 10228.2 8945.4 9029.1 9049.4 9172.5 9269.1 8843.6 8862.2 8903.6 8910.3 8992.8 7765.7 7892.1 7940.8 7954.5 7976.2 5820.7 5829.0 5900.5 5900.6 6083.3 5975.3 5991.5 6036.6 6124.7 6145.4 09:30:43 INFO - PROCESS | 26787 | 09:30:43 INFO - PROCESS | 26787 | [#4] prototype.html Cycles:180 Average:4808.40 Median:5214.86 stddev:1266.98 (24.3%) stddev-sans-first:1226.50 09:30:43 INFO - PROCESS | 26787 | Values: 396.9 399.3 420.2 422.8 426.6 5020.4 5126.9 5158.6 5163.0 5247.8 5042.8 5111.5 5134.8 5141.5 5144.9 4482.6 4509.9 4519.3 4529.2 4547.8 4383.8 4493.3 4535.5 4546.2 4546.5 4448.4 4466.4 4482.9 4527.2 4562.8 4432.4 4495.5 4500.9 4516.6 4573.2 4123.8 4270.9 4404.4 4406.0 4413.9 5028.6 5032.4 5039.2 5080.5 5084.6 5159.5 5160.3 5161.9 5171.3 5234.4 5290.4 5458.6 5467.9 5472.8 5507.7 4738.3 4748.4 4752.0 4755.5 4772.8 5753.0 5765.9 5773.8 5813.3 5863.7 4737.2 4919.8 4951.3 4970.0 5013.3 5001.0 5019.0 5020.9 5050.9 5064.7 5142.3 5154.5 5167.4 5217.2 5241.4 5137.3 5396.7 5426.9 5432.0 5527.0 1524.8 1554.8 1558.6 1559.4 1561.8 1228.9 1238.9 1251.4 1258.9 1260.9 5173.4 5214.9 5241.4 5258.9 5264.6 5459.6 5529.8 5601.9 5614.8 5632.4 5214.8 5221.1 5227.1 5234.7 5263.6 2855.5 2947.2 2953.7 2953.9 2957.7 5730.8 5749.6 5760.9 5771.3 5774.0 5637.5 5645.4 5693.6 5698.9 5754.8 5452.6 5702.8 5749.1 5777.9 5785.5 5581.2 5611.4 5613.4 5620.4 5638.5 5251.2 5450.5 5482.9 5574.8 5612.1 5772.5 5785.3 5802.2 5822.1 5842.0 4643.9 4647.9 4652.7 4666.9 4684.2 5419.9 5695.8 5704.2 5708.9 5720.0 5506.9 5512.4 5533.8 5559.7 5578.6 5362.4 5404.9 5476.5 5517.5 5538.1 5085.9 5388.5 5419.8 5422.9 5427.4 5472.1 5510.3 5514.0 5516.4 5555.2 5205.8 5400.7 5410.8 5453.2 5474.9 09:30:43 INFO - PROCESS | 26787 | 09:30:43 INFO - PROCESS | 26787 | [#5] yui.html Cycles:180 Average:17533.76 Median:40094.90 stddev:21842.15 (54.5%) stddev-sans-first:21867.27 09:30:43 INFO - PROCESS | 26787 | Values: 800.5 807.2 849.3 874.1 876.4 15242.7 15340.2 15513.1 15781.4 16006.8 15023.8 15258.5 15265.5 15274.4 15294.4 819.6 835.6 856.9 860.5 868.2 828.1 853.5 860.9 861.5 861.6 908.0 908.4 909.7 911.0 911.8 902.3 902.8 903.1 905.2 905.2 669.2 669.4 669.7 669.8 669.9 14376.8 14421.2 14434.2 14658.9 14968.0 14707.4 15188.6 15387.5 15505.7 16089.2 12312.8 12739.9 12741.4 12776.7 13224.9 3678.6 3692.6 3699.9 3711.6 3774.8 84159.0 84973.0 87501.0 88173.6 88981.0 11716.1 11737.1 11739.2 11945.9 11963.9 22707.8 23066.2 23077.0 23130.2 23385.6 7899.7 7994.7 8025.9 8132.8 8265.0 4432.5 4432.9 4486.8 4641.9 4700.3 3343.7 3434.6 3633.5 3747.5 3821.7 623.3 623.4 626.1 644.5 645.5 713.3 729.4 746.5 750.0 758.7 10089.1 10282.8 10529.8 11050.3 11123.0 800.5 804.5 809.7 815.6 816.0 4867.3 5287.9 5405.1 5443.8 5470.3 67507.7 75243.6 75968.0 77768.6 77788.6 52113.0 65141.3 65809.3 65905.0 65938.7 58312.7 58833.4 58939.7 59182.7 59311.0 10130.7 10149.5 10311.1 10320.3 10324.7 39444.4 40745.4 40906.8 41295.2 41748.6 46043.5 47169.0 47674.3 51545.5 52416.7 13424.7 13610.3 13612.4 13630.6 13631.5 14516.3 14613.4 15551.9 15622.9 16188.2 14391.6 14756.4 14777.8 14790.5 16165.8 14478.3 14916.6 14984.4 15384.2 15595.2 13083.5 13121.5 13429.9 13600.7 14125.4 11272.4 11475.0 11808.8 12028.4 12078.6 11937.5 12080.8 12090.9 12188.3 12194.8 09:30:43 INFO - PROCESS | 26787 | -------- Summary: end -------- 09:30:43 INFO - PROCESS | 26787 | 09:30:44 INFO - TEST-INFO | 26787: exit 0 09:30:44 INFO - TEST-OK | dromaeo_css | took 1117353ms 09:30:44 INFO - TEST-START | kraken 09:30:44 INFO - operating with platform_type : linux_ 09:30:44 INFO - Initialising browser for kraken test... 09:30:44 INFO - TEST-INFO | started process 27115 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiljBdm/profile http://localhost:58136/getInfo.html) 09:30:48 INFO - PROCESS | 27115 | __metrics Screen width/height:1600/1200 09:30:48 INFO - PROCESS | 27115 | colorDepth:24 09:30:48 INFO - PROCESS | 27115 | Browser inner width/height: 1024/697 09:30:48 INFO - PROCESS | 27115 | __metrics 09:30:49 INFO - PROCESS | 27115 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:30:49 INFO - PROCESS | 27115 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:30:50 INFO - TEST-INFO | 27115: exit 0 09:30:50 INFO - Browser initialized. 09:30:50 INFO - Running cycle 1/1 for kraken test... 09:30:50 INFO - TEST-INFO | started process 27205 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiljBdm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:30:50 CRITICAL - PROCESS | 27205 | Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 09:30:50 INFO - Terminating psutil.Process(pid=27205, name='firefox') 09:30:50 INFO - TEST-INFO | 27205: exit 127 09:30:50 INFO - TEST-UNEXPECTED-ERROR | kraken | Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 09:30:50 ERROR - Traceback (most recent call last): 09:30:50 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 09:30:50 INFO - talos_results.add(mytest.runTest(browser_config, test)) 09:30:50 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 70, in runTest 09:30:50 INFO - return self._runTest(browser_config, test_config, setup) 09:30:50 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 236, in _runTest 09:30:50 INFO - else None) 09:30:50 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 94, in add 09:30:50 INFO - global_counters=self.global_counters 09:30:50 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 311, in __init__ 09:30:50 INFO - self.parse() 09:30:50 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 338, in parse 09:30:50 INFO - % self.report_tokens) 09:30:50 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 322, in error 09:30:50 INFO - raise utils.TalosError(message) 09:30:50 INFO - TalosError: Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 09:30:50 INFO - TEST-INFO took 6099ms 09:30:50 INFO - SUITE-END | took 1123s 09:30:50 ERROR - Return code: 2 09:30:50 WARNING - setting return code to 2 09:30:50 CRITICAL - # TBPL RETRY # 09:30:50 WARNING - setting return code to 4 09:30:50 INFO - Running post-action listener: _resource_record_post_action 09:30:50 INFO - [mozharness: 2017-03-03 17:30:50.971522Z] Finished run-tests step (success) 09:30:50 INFO - Running post-run listener: _resource_record_post_run 09:30:51 INFO - Total resource usage - Wall time: 1140s; CPU: 13.0%; Read bytes: 0; Write bytes: 315727872; Read time: 0; Write time: 205800 09:30:51 INFO - TinderboxPrint: CPU usage
13.3% 09:30:51 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:30:51 INFO - TinderboxPrint: I/O write bytes / time
315,727,872 / 205,800 09:30:51 INFO - TinderboxPrint: CPU idle
7,885.1 (86.7%) 09:30:51 INFO - TinderboxPrint: CPU user
1,172.1 (12.9%) 09:30:51 INFO - TinderboxPrint: Swap in / out
0 / 0 09:30:51 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 111345664; Read time: 0; Write time: 88432 09:30:51 INFO - run-tests - Wall time: 1124s; CPU: 13.0%; Read bytes: 0; Write bytes: 204382208; Read time: 0; Write time: 117368 09:30:51 INFO - Running post-run listener: _upload_blobber_files 09:30:51 INFO - Blob upload gear active. 09:30:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:30:51 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 09:30:51 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:30:51 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:30:52 INFO - (blobuploader) - INFO - Open directory for files ... 09:30:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:30:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:30:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:57 INFO - (blobuploader) - INFO - Done attempting. 09:30:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:30:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:30:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:58 INFO - (blobuploader) - INFO - Done attempting. 09:30:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:30:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:30:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:59 INFO - (blobuploader) - INFO - Done attempting. 09:30:59 INFO - (blobuploader) - INFO - Iteration through files over. 09:30:59 INFO - Return code: 0 09:30:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:30:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:30:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2edcfd3c137842ae7fffe49c6c178b7e55bde76cd6b1b93b512c9a341e0cabd5121f5d9a087e6b507557ae4b20eaeda291e0686656fecd156f8929d40de34a15", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b0a3fa3181886349c2d354757adbaa9347503542a152bded59f0a791e1d6a8dbeedd590742988cae06de0f46b4742f2e15e2b912a8fb4d51fbec25ab1ae2d7a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/946997c30255924716cadd36d3728ac21b178bbb0dd250683151828140f30e1a1e047bab37958a420a78578c2f1e0dffb89eb8fa07cf6728b1398c2242732889"} 09:30:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:30:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:30:59 INFO - Contents: 09:30:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2edcfd3c137842ae7fffe49c6c178b7e55bde76cd6b1b93b512c9a341e0cabd5121f5d9a087e6b507557ae4b20eaeda291e0686656fecd156f8929d40de34a15", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b0a3fa3181886349c2d354757adbaa9347503542a152bded59f0a791e1d6a8dbeedd590742988cae06de0f46b4742f2e15e2b912a8fb4d51fbec25ab1ae2d7a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/946997c30255924716cadd36d3728ac21b178bbb0dd250683151828140f30e1a1e047bab37958a420a78578c2f1e0dffb89eb8fa07cf6728b1398c2242732889"} 09:30:59 INFO - Running post-run listener: copy_logs_to_upload_dir 09:30:59 INFO - Copying logs to upload dir... 09:30:59 INFO - mkdir: /builds/slave/test/build/upload/logs 09:30:59 INFO - Copying logs to upload dir... 09:30:59 WARNING - returning nonzero exit status 4 program finished with exit code 4 elapsedTime=1200.199652 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 20 mins, 0 secs) (at 2017-03-03 09:30:59.172859) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-03 09:30:59.176083) ========= 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=41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2edcfd3c137842ae7fffe49c6c178b7e55bde76cd6b1b93b512c9a341e0cabd5121f5d9a087e6b507557ae4b20eaeda291e0686656fecd156f8929d40de34a15", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b0a3fa3181886349c2d354757adbaa9347503542a152bded59f0a791e1d6a8dbeedd590742988cae06de0f46b4742f2e15e2b912a8fb4d51fbec25ab1ae2d7a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/946997c30255924716cadd36d3728ac21b178bbb0dd250683151828140f30e1a1e047bab37958a420a78578c2f1e0dffb89eb8fa07cf6728b1398c2242732889"} build_url:https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011270 build_url: 'https://queue.taskcluster.net/v1/task/WV0FVq3eQ4-1zlE05Tko5Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2edcfd3c137842ae7fffe49c6c178b7e55bde76cd6b1b93b512c9a341e0cabd5121f5d9a087e6b507557ae4b20eaeda291e0686656fecd156f8929d40de34a15", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/b0a3fa3181886349c2d354757adbaa9347503542a152bded59f0a791e1d6a8dbeedd590742988cae06de0f46b4742f2e15e2b912a8fb4d51fbec25ab1ae2d7a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/946997c30255924716cadd36d3728ac21b178bbb0dd250683151828140f30e1a1e047bab37958a420a78578c2f1e0dffb89eb8fa07cf6728b1398c2242732889"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-03 09:30:59.202806) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 09:30:59.203365) ========= 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=41a7e32711b8e08598a63237000001d6-1488560893.98260-663811389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004411 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-03 09:30:59.286992) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-03 09:30:59.287334) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-03 09:30:59.287751) ========= ========= Total master_lag: 0.18 =========